Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/76/

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:40070

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting 
response from server at: http://127.0.0.1:40070
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:601)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:399)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:457)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:179)
        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:498)
        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:992)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        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:495)
        ... 47 more




Build Log:
[...truncated 11818 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/init-core-data-001
   [junit4]   2> 2921128 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[AFFDD8DE45FE7A36]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 2921134 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[AFFDD8DE45FE7A36]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2921136 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2921142 INFO  (Thread-21432) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2921143 INFO  (Thread-21432) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2921391 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:43675
   [junit4]   2> 2921391 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2921392 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2921418 INFO  (zkCallback-1593-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@3d8cbd81 
name:ZooKeeperConnection Watcher:127.0.0.1:43675 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2921418 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2921419 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2921419 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2921427 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2921441 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2921450 INFO  (zkCallback-1594-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@35a8aed1 
name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2921450 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2921451 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2921451 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 2921458 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 2921459 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 2921459 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 2921460 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 2921460 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2921461 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 2921461 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 2921462 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2921462 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2921463 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 2921463 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2921463 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 2921463 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2921464 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 2921464 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 2921465 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2921465 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2921465 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2921465 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2921466 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2921466 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2921467 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2921467 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2921467 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 2921467 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2921615 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1
   [junit4]   2> 2921627 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2921661 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@748fa80c{/,null,AVAILABLE}
   [junit4]   2> 2921662 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@6eecf33f{HTTP/1.1,[http/1.1]}{127.0.0.1:45398}
   [junit4]   2> 2921662 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.e.j.s.Server Started @2924880ms
   [junit4]   2> 2921662 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/tempDir-001/control/data,
 hostContext=/, hostPort=45398, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores}
   [junit4]   2> 2921662 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 2921662 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001'
   [junit4]   2> 2921662 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2921662 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 2921663 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2921669 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2921670 INFO  (zkCallback-1595-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@35aee0d8 
name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2921670 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2921670 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2921671 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2921671 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/solr.xml
   [junit4]   2> 2921674 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores
   [junit4]   2> 2921674 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1345964234
   [junit4]   2> 2921674 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001]
   [junit4]   2> 2921674 WARN  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/lib
   [junit4]   2> 2921674 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
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> 2921675 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2921675 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2921676 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2921676 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43675/solr
   [junit4]   2> 2921676 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2921676 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2921679 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2921679 INFO  (zkCallback-1598-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@341c2131 
name:ZooKeeperConnection Watcher:127.0.0.1:43675 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2921679 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2921679 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2921680 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.ZkController Added new OnReconnect listener 
org.apache.solr.cloud.ZkController$$Lambda$38/2075211641@612d4e90
   [junit4]   2> 2921682 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 2921683 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@459f57ed 
name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2921683 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 2921683 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 2921684 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 2921685 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 2921685 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 2921686 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 2921686 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2921687 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2921687 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2921688 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2921688 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 2921688 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 2921689 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 2921689 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 2921690 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 2921690 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2921691 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.LeaderElector Joined leadership election with 
path: /overseer_elect/election/95975996557295620-127.0.0.1:45398_-n_0000000000
   [junit4]   2> 2921691 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.OverseerElectionContext I am going to be the 
leader 127.0.0.1:45398_
   [junit4]   2> 2921691 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 2921691 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.Overseer Overseer 
(id=95975996557295620-127.0.0.1:45398_-n_0000000000) starting
   [junit4]   2> 2921692 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 2921694 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=10000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 2921694 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 2921694 INFO  
(OverseerCollectionConfigSetProcessor-95975996557295620-127.0.0.1:45398_-n_0000000000)
 [n:127.0.0.1:45398_    ] o.a.s.c.OverseerTaskProcessor Process current queue 
of overseer operations
   [junit4]   2> 2921702 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:45398_
   [junit4]   2> 2921703 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:45398_
   [junit4]   2> 2921703 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 2921703 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live 
nodes size: [0])
   [junit4]   2> 2921703 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2921722 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 2921722 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2921722 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.CorePropertiesLocator Looking for core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores
   [junit4]   2> 2921723 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] 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> 2921723 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.CorePropertiesLocator Found core collection1 
in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1
   [junit4]   2> 2921723 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 2921724 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 2921724 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 2921724 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Collection control_collection not visible yet, but flagging it so a watch is 
registered when it becomes visible
   [junit4]   2> 2921725 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 2921725 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1
   [junit4]   2> 2921725 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:45398_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() 
done
   [junit4]   2> 2921726 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 2921726 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] 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:45398";,
   [junit4]   2>   "node_name":"127.0.0.1:45398_",
   [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> 2921726 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] 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:45398";,
   [junit4]   2>   "node_name":"127.0.0.1:45398_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2921726 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
control_collection
   [junit4]   2> 2921726 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 2921727 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] 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> 2922726 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2922726 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:control_collection
   [junit4]   2> 2922727 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 2922727 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config from: 
[/collections/control_collection]
   [junit4]   2> 2922727 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=[/collections/control_collection] 
[configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 2922727 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1'
   [junit4]   2> 2922727 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2922727 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 2922728 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 2922730 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 2922732 WARN  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
instead.
   [junit4]   2> 2922737 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.1.0
   [junit4]   2> 2922743 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2922961 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 2923130 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2923149 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
default search field in schema is text
   [junit4]   2> 2923150 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 2923154 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2923156 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2923157 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2923157 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2923158 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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-6.x/solr/build/solr-core/test/J1
   [junit4]   2> 2923158 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
control_collection
   [junit4]   2> 2923158 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2923158 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/]
   [junit4]   2> 2923158 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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@5a3da9f9
   [junit4]   2> 2923158 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data
   [junit4]   2> 2923166 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/index/
   [junit4]   2> 2923166 WARN  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2923166 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/index
   [junit4]   2> 2923166 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=9, maxMergeAtOnceExplicit=4, maxMergedSegmentMB=88.5, 
floorSegmentMB=0.9404296875, forceMergeDeletesPctAllowed=6.753991068492562, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=1.3935546875, noCFSRatio=0.0
   [junit4]   2> 2923166 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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@4289e8f3 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11fd0ee5),segFN=segments_1,generation=1}
   [junit4]   2> 2923166 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2923168 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 2923168 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 2923168 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2923168 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 2923168 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2923168 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923168 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923169 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923169 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923169 WARN  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923170 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2923170 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2923171 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2923171 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2923173 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
   [junit4]   2> 2923173 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923173 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45398_ 
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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/
   [junit4]   2> 2923173 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 2923173 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923173 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923174 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2923174 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2923174 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=67.6943359375, 
floorSegmentMB=0.3505859375, forceMergeDeletesPctAllowed=12.317429438353683, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2923174 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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@4289e8f3 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11fd0ee5),segFN=segments_1,generation=1}
   [junit4]   2> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3798da33[collection1] main]
   [junit4]   2> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923175 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923176 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923176 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2923176 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2923176 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923176 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923176 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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> 2923176 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ 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 1535615947036950528
   [junit4]   2> 2923178 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 
1535615947036950528
   [junit4]   2> 2923178 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 2923178 INFO  
(coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) 
[n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2923178 INFO  
(searcherExecutor-4488-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
[collection1] Registered new searcher Searcher@3798da33[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2923179 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
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:45398 
collection:control_collection shard:shard1
   [junit4]   2> 2923179 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
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> 2923180 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
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/95975996557295620-core_node1-n_0000000000
   [junit4]   2> 2923180 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
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> 2923181 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] 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> 2923182 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2923182 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
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> 2923182 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:45398/collection1/
   [junit4]   2> 2923182 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 2923182 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:45398/collection1/ has no replicas
   [junit4]   2> 2923182 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 2923183 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/control_collection/leaders/shard1/leader after winning as 
/collections/control_collection/leader_elect/shard1/election/95975996557295620-core_node1-n_0000000000
   [junit4]   2> 2923184 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] 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:45398";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 2923185 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
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:45398/collection1/ shard1
   [junit4]   2> 2923226 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 
127.0.0.1:43675/solr
   [junit4]   2> 2923226 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2923243 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2923244 INFO  (zkCallback-1603-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@33980ed9 
name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2923245 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2923245 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2923245 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2923245 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 2923245 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2923246 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 2923285 INFO  (zkCallback-1603-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: [1])
   [junit4]   2> 2923285 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] 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> 2923335 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
We are http://127.0.0.1:45398/collection1/ and leader is 
http://127.0.0.1:45398/collection1/
   [junit4]   2> 2923335 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
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:45398
   [junit4]   2> 2923335 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 2923335 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
publishing state=active
   [junit4]   2> 2923336 INFO  
(coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 
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> 2923336 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] 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:45398";,
   [junit4]   2>   "node_name":"127.0.0.1:45398_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 2923337 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 
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:45398";,
   [junit4]   2>   "node_name":"127.0.0.1:45398_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2923362 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores/collection1
   [junit4]   2> 2923363 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001
   [junit4]   2> 2923363 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2923386 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@44cd5e15{/,null,AVAILABLE}
   [junit4]   2> 2923387 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@7496adb1{HTTP/1.1,[http/1.1]}{127.0.0.1:40070}
   [junit4]   2> 2923387 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.e.j.s.Server Started @2926605ms
   [junit4]   2> 2923387 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/tempDir-001/jetty1,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=40070, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores}
   [junit4]   2> 2923387 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 2923387 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001'
   [junit4]   2> 2923387 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2923387 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 2923387 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2923410 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2923422 INFO  (zkCallback-1604-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@37eedd07 
name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2923422 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2923423 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2923423 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2923423 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/solr.xml
   [junit4]   2> 2923427 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores
   [junit4]   2> 2923427 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 622539282
   [junit4]   2> 2923427 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001]
   [junit4]   2> 2923427 WARN  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/lib
   [junit4]   2> 2923427 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
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> 2923428 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2923428 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2923428 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2923428 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43675/solr
   [junit4]   2> 2923428 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2923428 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2923438 INFO  (zkCallback-1603-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: [1])
   [junit4]   2> 2923438 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] 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> 2923443 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2923450 INFO  (zkCallback-1607-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@42b8f654 
name:ZooKeeperConnection Watcher:127.0.0.1:43675 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2923450 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2923451 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2923451 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.ZkController Added new OnReconnect listener 
org.apache.solr.cloud.ZkController$$Lambda$38/2075211641@5312ba4f
   [junit4]   2> 2923463 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 2923464 INFO  
(zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_    
] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4f88c0cb 
name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2923464 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 2923466 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 2923467 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 2923467 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2923468 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2923468 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.LeaderElector Joined leadership election with 
path: /overseer_elect/election/95975996557295624-127.0.0.1:40070_-n_0000000001
   [junit4]   2> 2923468 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.LeaderElector Watching path 
/overseer_elect/election/95975996557295620-127.0.0.1:45398_-n_0000000000 to 
know if I could be the leader
   [junit4]   2> 2923468 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.ZkController Publish node=127.0.0.1:40070_ as 
DOWN
   [junit4]   2> 2923469 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:40070_"} current state version: 3
   [junit4]   2> 2923469 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 
127.0.0.1:40070_
   [junit4]   2> 2923570 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] 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> 2923570 INFO  
(zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_    
] 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> 2923570 INFO  (zkCallback-1603-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: [1])
   [junit4]   2> 2924469 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:40070_
   [junit4]   2> 2924469 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:40070_
   [junit4]   2> 2924470 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] 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> 2924470 INFO  (zkCallback-1603-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> 2924470 INFO  (zkCallback-1603-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2924470 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2924470 INFO  
(zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_    
] 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> 2924471 INFO  
(zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2924487 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 2924487 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2924488 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.CorePropertiesLocator Looking for core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores
   [junit4]   2> 2924488 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] 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> 2924488 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.CorePropertiesLocator Found core collection1 
in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores/collection1
   [junit4]   2> 2924488 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 2924489 INFO  
(coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) 
[n:127.0.0.1:40070_ c:collection1   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 2924489 INFO  
(coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) 
[n:127.0.0.1:40070_ c:collection1   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 2924490 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] 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:40070";,
   [junit4]   2>   "node_name":"127.0.0.1:40070_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 4
   [junit4]   2> 2924490 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] 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:40070";,
   [junit4]   2>   "node_name":"127.0.0.1:40070_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2924490 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
collection1
   [junit4]   2> 2924490 INFO  
(OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) 
[n:127.0.0.1:45398_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard2
   [junit4]   2> 2924491 INFO  
(coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) 
[n:127.0.0.1:40070_ c:collection1   x:collection1] o.a.s.c.ZkController 
Collection collection1 not visible yet, but flagging it so a watch is 
registered when it becomes visible
   [junit4]   2> 2924491 INFO  
(coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) 
[n:127.0.0.1:40070_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader 
Deleting data for [collection1]
   [junit4]   2> 2924492 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1
   [junit4]   2> 2924492 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) 
[n:127.0.0.1:40070_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() 
done
   [junit4]   2> 2924492 INFO  
(coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) 
[n:127.0.0.1:40070_ c:collection1   x:collection1] o.a.s.c.ZkController look 
for our core node name
   [junit4]   2> 2924591 INFO  
(zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_    
] 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> 2924591 INFO  (zkCallback-1603-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> 2924591 INFO  
(zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_    
] 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> 2925492 INFO  
(coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) 
[n:127.0.0.1:40070_ c:collection1   x:collection1] o.a.s.c.ZkController waiting 
to find shard id in clusterstate for collection1
   [junit4]   2> 2925492 INFO  
(coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) 
[n:127.0.0.1:40070_ c:collection1   x:collection1] o.a.s.c.ZkController Check 
for collection zkNode:collection1
   [junit4]   2> 2925493 INFO  (coreLoadExecutor-4498-thread-1-pr

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

g-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/analysis/icu/lucene-analyzers-icu-6.1.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/contrib/analysis-extras/lib/icu4j-56.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.4.jar
 com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J0-20160528_222922_117.events
 
@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J0-20160528_222922_117.suites
 -stdin
   [junit4] ERROR: JVM J0 ended with an exception: Quit event not received from 
the forked process? This may indicate JVM crash or runner bugs.
   [junit4]     at 
com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1501)
   [junit4]     at 
com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
   [junit4]     at 
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
   [junit4]     at 
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
   [junit4]     at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]     at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]     at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]     at java.lang.Thread.run(Thread.java:745)

BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:531:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1427:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984:
 At least one slave process threw an exception, first: Quit event not received 
from the forked process? This may indicate JVM crash or runner bugs.

Total time: 305 minutes 55 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



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to