lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-trunk - Build # 929 - Still Failing
Date Tue, 09 Feb 2016 11:57:56 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/929/

1 tests failed.
FAILED:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test

Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:39508/t_/f

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:39508/t_/f
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:587)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:375)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection(CollectionsAPIDistributedZkTest.java:236)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:164)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:964)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
	at java.net.SocketInputStream.socketRead0(Native Method)
	at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
	at java.net.SocketInputStream.read(SocketInputStream.java:170)
	at java.net.SocketInputStream.read(SocketInputStream.java:141)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
	at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
	at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
	at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:481)
	... 47 more




Build Log:
[...truncated 11708 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/init-core-data-001
   [junit4]   2> 2195856 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[9C835ACF487FE3D2]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 2195857 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[9C835ACF487FE3D2]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /t_/f
   [junit4]   2> 2195859 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2195860 INFO  (Thread-19858) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2195860 INFO  (Thread-19858) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2195960 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.ZkTestServer start zk server on port:48615
   [junit4]   2> 2195960 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2195960 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2195966 INFO  (zkCallback-1591-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@31c0ae24 name:ZooKeeperConnection Watcher:127.0.0.1:48615 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2195967 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2195967 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2195967 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2195970 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2195970 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2195971 INFO  (zkCallback-1592-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@449c505b name:ZooKeeperConnection Watcher:127.0.0.1:48615/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2195971 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2195971 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2195971 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 2195973 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 2195974 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 2195975 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 2195976 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 2195977 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2195979 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 2195979 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 2195981 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2195981 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2195982 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 2195983 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2195984 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 2195984 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2195986 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 2195986 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 2195987 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2195988 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2195989 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2195989 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2195991 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2195991 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2195992 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2195993 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2195994 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 2195994 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2196086 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1
   [junit4]   2> 2196088 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 2196089 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2110ceef{/t_/f,null,AVAILABLE}
   [junit4]   2> 2196090 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.e.j.s.ServerConnector Started ServerConnector@3ed41fec{HTTP/1.1}{127.0.0.1:51230}
   [junit4]   2> 2196090 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.e.j.s.Server Started @2199383ms
   [junit4]   2> 2196090 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/tempDir-001/control/data, hostContext=/t_/f, hostPort=51230, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores}
   [junit4]   2> 2196090 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 2196090 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001'
   [junit4]   2> 2196090 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2196090 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2196091 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2196091 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2196092 INFO  (zkCallback-1593-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5a5d36e9 name:ZooKeeperConnection Watcher:127.0.0.1:48615/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2196092 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2196093 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2196093 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2196094 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/solr.xml
   [junit4]   2> 2196098 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores
   [junit4]   2> 2196098 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.CoreContainer New CoreContainer 895949995
   [junit4]   2> 2196098 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001]
   [junit4]   2> 2196099 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/lib
   [junit4]   2> 2196099 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 2196100 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2196101 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2196101 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2196101 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48615/solr
   [junit4]   2> 2196101 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2196101 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2196101 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2196102 INFO  (zkCallback-1596-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58f4b67 name:ZooKeeperConnection Watcher:127.0.0.1:48615 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2196102 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2196103 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2196104 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2196105 INFO  (zkCallback-1597-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@20dc5e54 name:ZooKeeperConnection Watcher:127.0.0.1:48615/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2196105 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2196106 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 2196108 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 2196111 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 2196113 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 2196115 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 2196117 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2196118 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2196120 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2196121 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2196122 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51230_t_%2Ff
   [junit4]   2> 2196122 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51230_t_%2Ff
   [junit4]   2> 2196124 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 2196125 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 2196126 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2196127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000
   [junit4]   2> 2196127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51230_t_%2Ff
   [junit4]   2> 2196127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 2196129 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.Overseer Overseer (id=95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) starting
   [junit4]   2> 2196130 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 2196136 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 2196137 INFO  (OverseerCollectionConfigSetProcessor-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 2196137 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2196137 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 2196152 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 2196152 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2196153 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores
   [junit4]   2> 2196153 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 2196153 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1
   [junit4]   2> 2196154 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 2196154 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2
   [junit4]   2> 2196154 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 2196155 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2196155 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 2196156 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:51230/t_/f",
   [junit4]   2>   "node_name":"127.0.0.1:51230_t_%2Ff",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 2196156 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:51230/t_/f",
   [junit4]   2>   "node_name":"127.0.0.1:51230_t_%2Ff",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2196157 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 2196157 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 2196157 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2196158 INFO  (zkCallback-1597-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2197157 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2197157 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 2197158 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 2197158 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 2197159 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 2197159 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1'
   [junit4]   2> 2197159 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2197159 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2197161 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 2197165 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2197170 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2197179 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2197185 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2197297 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2197302 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2197304 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2197311 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2197313 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2197315 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2197316 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2197317 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2
   [junit4]   2> 2197317 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 2197317 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2197318 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1/data/]
   [junit4]   2> 2197318 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@231adbce
   [junit4]   2> 2197318 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1/data
   [junit4]   2> 2197318 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1/data/index/
   [junit4]   2> 2197318 WARN  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 2197319 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1/data/index
   [junit4]   2> 2197319 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10513104598787884]
   [junit4]   2> 2197320 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@15a68a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f4f2de2),segFN=segments_1,generation=1}
   [junit4]   2> 2197320 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2197320 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1/data/
   [junit4]   2> 2197320 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 2197323 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 2197323 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2197323 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2197323 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2197323 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2197323 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2197323 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2197324 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2197324 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 2197324 WARN  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 2197325 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2197326 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2197327 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2197328 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2197331 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 2197332 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2197332 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2197332 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2197333 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2197333 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2197333 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30312164634894795]
   [junit4]   2> 2197334 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@15a68a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f4f2de2),segFN=segments_1,generation=1}
   [junit4]   2> 2197334 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2197334 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a87640[collection1] main]
   [junit4]   2> 2197334 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 2197335 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 2197335 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 2197335 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2197335 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 2197335 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2197336 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 2197336 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2197336 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2197336 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2197337 INFO  (searcherExecutor-5383-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a87640[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2197337 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 2197337 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 2197337 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 2197337 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1525695594385899520
   [junit4]   2> 2197339 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1525695594385899520
   [junit4]   2> 2197339 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 2197339 INFO  (coreLoadExecutor-5382-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2197340 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:51230/t_/f collection:control_collection shard:shard1
   [junit4]   2> 2197341 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 2197344 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/95355974552387588-core_node1-n_0000000000
   [junit4]   2> 2197344 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000
   [junit4]   2> 2197345 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2197345 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2197345 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51230/t_/f/collection1/
   [junit4]   2> 2197346 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2197346 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection"} current state version: 1
   [junit4]   2> 2197346 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51230/t_/f/collection1/ has no replicas
   [junit4]   2> 2197346 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 2197348 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 2197350 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51230/t_/f/collection1/ shard1
   [junit4]   2> 2197350 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:51230/t_/f",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 2197474 INFO  (zkCallback-1597-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2197503 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:51230/t_/f/collection1/ and leader is http://127.0.0.1:51230/t_/f/collection1/
   [junit4]   2> 2197503 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51230/t_/f
   [junit4]   2> 2197503 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2197503 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 2197503 INFO  (coreZkRegister-5376-thread-1-processing-n:127.0.0.1:51230_t_%2Ff x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51230_t_%2Ff c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 2197505 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:51230/t_/f",
   [junit4]   2>   "node_name":"127.0.0.1:51230_t_%2Ff",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 2197505 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:51230/t_/f",
   [junit4]   2>   "node_name":"127.0.0.1:51230_t_%2Ff",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2197607 INFO  (zkCallback-1597-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2197656 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2197658 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2197658 INFO  (zkCallback-1599-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58883e41 name:ZooKeeperConnection Watcher:127.0.0.1:48615/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2197659 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2197659 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2197659 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2197661 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 2197752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/cores/collection1
   [junit4]   2> 2197753 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001
   [junit4]   2> 2197754 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 2197758 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@67ab18b7{/t_/f,null,AVAILABLE}
   [junit4]   2> 2197758 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.e.j.s.ServerConnector Started ServerConnector@20311222{HTTP/1.1}{127.0.0.1:39508}
   [junit4]   2> 2197758 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.e.j.s.Server Started @2201052ms
   [junit4]   2> 2197759 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/t_/f, hostPort=39508, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/cores}
   [junit4]   2> 2197759 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 2197759 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001'
   [junit4]   2> 2197759 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2197759 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2197759 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2197760 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2197761 INFO  (zkCallback-1600-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23cab007 name:ZooKeeperConnection Watcher:127.0.0.1:48615/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2197761 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2197761 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2197762 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2197762 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/solr.xml
   [junit4]   2> 2197767 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/cores
   [junit4]   2> 2197767 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.CoreContainer New CoreContainer 218176719
   [junit4]   2> 2197767 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001]
   [junit4]   2> 2197768 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/lib
   [junit4]   2> 2197768 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 2197769 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2197769 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2197769 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2197770 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48615/solr
   [junit4]   2> 2197770 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2197770 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2197770 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2197771 INFO  (zkCallback-1603-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b693a6b name:ZooKeeperConnection Watcher:127.0.0.1:48615 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2197771 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2197772 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2197773 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2197774 INFO  (zkCallback-1604-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@32acf0ba name:ZooKeeperConnection Watcher:127.0.0.1:48615/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2197774 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2197778 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2198783 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39508_t_%2Ff
   [junit4]   2> 2198784 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:39508_t_%2Ff
   [junit4]   2> 2198785 INFO  (zkCallback-1597-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2198785 INFO  (zkCallback-1599-thread-1) [    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2198786 INFO  (zkCallback-1604-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2198788 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2198788 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95355974552387592-127.0.0.1:39508_t_%2Ff-n_0000000001
   [junit4]   2> 2198789 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000 to know if I could be the leader
   [junit4]   2> 2198799 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 2198799 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2198800 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/cores
   [junit4]   2> 2198801 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 2198801 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/cores/collection1
   [junit4]   2> 2198801 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 2198803 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 2198803 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 2198803 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2
   [junit4]   2> 2198803 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[9C835ACF487FE3D2]) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2198804 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 2198804 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:39508/t_/f",
   [junit4]   2>   "node_name":"127.0.0.1:39508_t_%2Ff",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 3
   [junit4]   2> 2198805 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:39508/t_/f",
   [junit4]   2>   "node_name":"127.0.0.1:39508_t_%2Ff",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2198805 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 2198805 INFO  (OverseerStateUpdate-95355974552387588-127.0.0.1:51230_t_%2Ff-n_0000000000) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 2198907 INFO  (zkCallback-1597-thread-1-processing-n:127.0.0.1:51230_t_%2Ff) [n:127.0.0.1:51230_t_%2Ff    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2198907 INFO  (zkCallback-1604-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2198907 INFO  (zkCallback-1599-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2199805 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2199805 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
   [junit4]   2> 2199805 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 2199805 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1]
   [junit4]   2> 2199806 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 2199806 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001/shard-1-001/cores/collection1'
   [junit4]   2> 2199806 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2199806 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2199808 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 2199813 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2199817 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2199827 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2199832 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2199947 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2199953 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2199955 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2199963 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2199965 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2199967 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2199967 INFO  (coreLoadExecutor-5393-thread-1-processing-n:127.0.0.1:39508_t_%2Ff) [n:127.0.0.1:39508_t_%2Ff c:collection1   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open

[...truncated too long message...]

lient.request(HttpSolrClient.java:240)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614)
   [junit4]    > 	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:966)Throwable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=28242, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:49674/t_/f: collection already exists: awholynewstresscollection_collection2_6
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([9C835ACF487FE3D2]:0)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614)
   [junit4]    > 	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:966)Throwable #7: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=28243, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39508/t_/f: Could not find collection : awholynewstresscollection_collection3_5
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([9C835ACF487FE3D2]:0)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]    > 	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:973)
   [junit4]   2> 2843976 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[9C835ACF487FE3D2]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_9C835ACF487FE3D2-001
   [junit4]   2> Feb 09, 2016 11:29:09 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=PostingsFormat(name=Asserting), range_facet_l_dv=PostingsFormat(name=MockRandom), _version_=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=Memory doPackFST= true), a_t=PostingsFormat(name=Asserting), intDefault=PostingsFormat(name=Asserting), a_i1=PostingsFormat(name=Asserting), id=PostingsFormat(name=MockRandom), range_facet_i_dv=PostingsFormat(name=Asserting), text=BlockTreeOrds(blocksize=128), range_facet_l=PostingsFormat(name=Asserting), timestamp=PostingsFormat(name=Asserting)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, sim=ClassicSimilarity, locale=th-TH, timezone=SST
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=197495120,total=504889344
   [junit4]   2> NOTE: All tests run in this JVM: [TestJettySolrRunner, TestFreeTextSuggestions, ConcurrentDeleteAndCreateCollectionTest, TestSolrQueryParser, FullHLLTest, TestReloadDeadlock, TestStressUserVersions, TestDefaultSearchFieldResource, HdfsTlogReplayBufferedWhileIndexingTest, HdfsChaosMonkeyNothingIsSafeTest, LukeRequestHandlerTest, TermVectorComponentDistributedTest, CurrencyFieldXmlFileTest, TestCloudSchemaless, AnalyticsMergeStrategyTest, CachingDirectoryFactoryTest, TestFieldTypeResource, SpatialHeatmapFacetsTest, RecoveryAfterSoftCommitTest, HighlighterConfigTest, AssignTest, TestRawTransformer, DateMathParserTest, OutOfBoxZkACLAndCredentialsProvidersTest, UnloadDistributedZkTest, TestConfigSetImmutable, TestCharFilters, TestManagedResource, PreAnalyzedUpdateProcessorTest, TestJmxMonitoredMap, SaslZkACLProviderTest, SearchHandlerTest, TestSchemaResource, DistributedFacetPivotSmallTest, StressHdfsTest, TestSolrJ, CdcrRequestHandlerTest, TestAnalyzeInfixSuggestions, SolrTestCaseJ4Test, TestSweetSpotSimilarityFactory, TestRequestForwarding, TestCSVResponseWriter, TestDistribDocBasedVersion, SolrXmlInZkTest, TestExtendedDismaxParser, BadComponentTest, DirectSolrConnectionTest, WrapperMergePolicyFactoryTest, TestJmxIntegration, CleanupOldIndexTest, RestartWhileUpdatingTest, ClusterStateTest, TestRestoreCore, TestRecovery, HttpPartitionTest, TestConfigReload, TestCollationField, OverseerRolesTest, SimpleFacetsTest, StatsComponentTest, XmlUpdateRequestHandlerTest, TestCoreDiscovery, DistributedQueryComponentOptimizationTest, TestFuzzyAnalyzedSuggestions, DistribCursorPagingTest, SampleTest, TestDFISimilarityFactory, DocExpirationUpdateProcessorFactoryTest, TestAuthorizationFramework, TestSolrCLIRunExample, TestJsonFacets, TestExceedMaxTermLength, RecoveryZkTest, TestMaxScoreQueryParser, MergeStrategyTest, HardAutoCommitTest, TestSort, TestBulkSchemaAPI, TestLRUStatsCache, TestCloudPivotFacet, TestPHPSerializedResponseWriter, TestSha256AuthenticationProvider, TestPostingsSolrHighlighter, NoCacheHeaderTest, HdfsDirectoryFactoryTest, BasicDistributedZk2Test, TestComplexPhraseQParserPlugin, AddSchemaFieldsUpdateProcessorFactoryTest, TestTrackingShardHandlerFactory, TestSolrDeletionPolicy1, CoreAdminHandlerTest, TestShardHandlerFactory, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest]
   [junit4] Completed [264/579 (1!)] on J2 in 648.65s, 1 test, 1 error <<< FAILURES!

[...truncated 1011 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:524: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1014: There were test failures: 579 suites (8 ignored), 2323 tests, 1 error, 33 ignored (18 assumptions) [seed: 9C835ACF487FE3D2]

Total time: 246 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



Mime
View raw message