Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/963/

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

Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:52666

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting 
response from server at: http://127.0.0.1:52666
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:381)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:497)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:169)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
        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:482)
        ... 47 more


FAILED:  org.apache.solr.cloud.hdfs.HdfsUnloadDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=24027, 
name=testExecutor-5750-thread-3, state=RUNNABLE, 
group=TGRP-HdfsUnloadDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=24027, name=testExecutor-5750-thread-3, 
state=RUNNABLE, group=TGRP-HdfsUnloadDistributedZkTest]
Caused by: java.lang.RuntimeException: 
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting 
response from server at: http://127.0.0.1:43392/z/ie
        at __randomizedtesting.SeedInfo.seed([733551967ABECAD3]:0)
        at 
org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$3(BasicDistributedZkTest.java:583)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$6(ExecutorUtil.java:229)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured 
while waiting response from server at: http://127.0.0.1:43392/z/ie
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
        at 
org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$3(BasicDistributedZkTest.java:581)
        ... 4 more
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:482)
        ... 8 more


FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler

Error Message:
ObjectTracker found 10 object(s) that were not released!!! 
[NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory]

Stack Trace:
java.lang.AssertionError: ObjectTracker found 10 object(s) that were not 
released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory]
        at __randomizedtesting.SeedInfo.seed([733551967ABECAD3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:238)
        at sun.reflect.GeneratedMethodAccessor21.invoke(Unknown Source)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
        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)




Build Log:
[...truncated 11829 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/init-core-data-001
   [junit4]   2> 1621487 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[733551967ABECAD3]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1621487 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[733551967ABECAD3]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1621492 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1621499 INFO  (Thread-8159) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1621500 INFO  (Thread-8159) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1621599 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:56014
   [junit4]   2> 1621600 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1621600 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1621605 INFO  (zkCallback-1545-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@15b3b048 
name:ZooKeeperConnection Watcher:127.0.0.1:56014 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1621605 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1621605 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1621605 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1621627 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1621631 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1621637 INFO  (zkCallback-1546-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5661c287 
name:ZooKeeperConnection Watcher:127.0.0.1:56014/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1621637 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1621637 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1621637 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1621647 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1621648 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1621649 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1621650 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 1621650 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1621652 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 1621652 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1621654 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1621654 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1621655 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 1621655 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1621660 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 1621661 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1621676 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 1621676 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1621687 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1621688 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1621690 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1621691 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1621695 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1621695 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1621699 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1621699 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1621703 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 1621703 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1621705 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1621705 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1621706 INFO  (zkCallback-1547-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@476bf28c 
name:ZooKeeperConnection Watcher:127.0.0.1:56014 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1621706 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1621706 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1621706 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1621708 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1621711 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1621772 INFO  (zkCallback-1548-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@f69e49a name:ZooKeeperConnection 
Watcher:127.0.0.1:56014/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 1621772 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1621772 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1621773 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf2/solrconfig.xml
   [junit4]   2> 1621773 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 1621776 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf2/schema.xml
   [junit4]   2> 1621776 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 1621777 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf2/enumsConfig.xml
   [junit4]   2> 1621778 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 1621779 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1621779 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1621786 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf2/stopwords.txt
   [junit4]   2> 1621786 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 1621788 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf2/protwords.txt
   [junit4]   2> 1621788 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 1621789 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf2/currency.xml
   [junit4]   2> 1621789 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 1621790 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1621790 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1621792 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1621792 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1621793 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf2/old_synonyms.txt
   [junit4]   2> 1621794 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 1621796 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf2/synonyms.txt
   [junit4]   2> 1621797 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 1621798 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/elevate.xml
 to /configs/conf2/elevate.xml
   [junit4]   2> 1621798 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 1621933 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1
   [junit4]   2> 1621946 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1622006 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@409dfbeb{/,null,AVAILABLE}
   [junit4]   2> 1622012 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@5b42fb4d{HTTP/1.1,[http/1.1]}{127.0.0.1:51672}
   [junit4]   2> 1622012 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.e.j.s.Server Started @1625106ms
   [junit4]   2> 1622012 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/tempDir-001/control/data,
 hostContext=/, hostPort=51672, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores}
   [junit4]   2> 1622012 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 1622013 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001'
   [junit4]   2> 1622013 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1622013 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1622013 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1622021 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1622030 INFO  (zkCallback-1549-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@50b1bf46 
name:ZooKeeperConnection Watcher:127.0.0.1:56014/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1622030 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1622030 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1622031 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1622031 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/solr.xml
   [junit4]   2> 1622039 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores
   [junit4]   2> 1622039 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1711783095
   [junit4]   2> 1622039 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001]
   [junit4]   2> 1622040 WARN  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/lib
   [junit4]   2> 1622040 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
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> 1622041 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1622042 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1622042 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1622042 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56014/solr
   [junit4]   2> 1622042 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1622042 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1622042 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1622044 INFO  (zkCallback-1552-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@81f637b name:ZooKeeperConnection 
Watcher:127.0.0.1:56014 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 1622044 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1622044 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1622046 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1622047 INFO  
(zkCallback-1553-thread-1-processing-n:127.0.0.1:51672_) [n:127.0.0.1:51672_    
] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7a99211c 
name:ZooKeeperConnection Watcher:127.0.0.1:56014/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1622047 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 1622050 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1622051 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 1622053 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 1622054 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 1622055 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 1622056 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1622060 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1622060 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1622061 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1622062 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 1622063 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 1622064 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1622065 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 1622066 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1622066 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.LeaderElector Joined leadership election with 
path: /overseer_elect/election/95565975869587462-127.0.0.1:51672_-n_0000000000
   [junit4]   2> 1622066 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.OverseerElectionContext I am going to be the 
leader 127.0.0.1:51672_
   [junit4]   2> 1622066 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1622067 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.Overseer Overseer 
(id=95565975869587462-127.0.0.1:51672_-n_0000000000) starting
   [junit4]   2> 1622068 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1622090 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=10000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1622098 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1622100 INFO  
(OverseerCollectionConfigSetProcessor-95565975869587462-127.0.0.1:51672_-n_0000000000)
 [n:127.0.0.1:51672_    ] o.a.s.c.OverseerTaskProcessor Process current queue 
of overseer operations
   [junit4]   2> 1622108 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:51672_
   [junit4]   2> 1622111 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:51672_
   [junit4]   2> 1622113 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 1622118 INFO  
(zkCallback-1553-thread-1-processing-n:127.0.0.1:51672_) [n:127.0.0.1:51672_    
] 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> 1622124 INFO  
(zkCallback-1553-thread-1-processing-n:127.0.0.1:51672_) [n:127.0.0.1:51672_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1622130 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 1622130 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1622130 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.CorePropertiesLocator Looking for core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores
   [junit4]   2> 1622131 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] 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> 1622131 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.CorePropertiesLocator Found core collection1 
in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1
   [junit4]   2> 1622131 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1622132 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 1622132 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 1622135 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 1622136 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] 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:51672";,
   [junit4]   2>   "node_name":"127.0.0.1:51672_",
   [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> 1622137 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] 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:51672";,
   [junit4]   2>   "node_name":"127.0.0.1:51672_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1622137 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2
   [junit4]   2> 1622137 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) 
[n:127.0.0.1:51672_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() 
done
   [junit4]   2> 1622138 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
control_collection
   [junit4]   2> 1622139 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 1622141 INFO  
(zkCallback-1553-thread-1-processing-n:127.0.0.1:51672_) [n:127.0.0.1:51672_    
] 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> 1623136 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1623136 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:control_collection
   [junit4]   2> 1623136 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 1623136 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config from: 
[/collections/control_collection]
   [junit4]   2> 1623137 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=[/collections/control_collection] 
[configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1623137 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1'
   [junit4]   2> 1623137 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1623137 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1623139 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 1623143 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 1623149 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1623196 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1623205 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 1623412 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1623421 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
default search field in schema is text
   [junit4]   2> 1623422 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 1623434 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1623436 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1623438 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1623438 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1623440 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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-master/solr/build/solr-core/test/J2
   [junit4]   2> 1623440 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
control_collection
   [junit4]   2> 1623440 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1623440 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1/data/]
   [junit4]   2> 1623440 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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@628be4a5
   [junit4]   2> 1623441 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1/data
   [junit4]   2> 1623441 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1623441 WARN  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1623441 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1/data/index
   [junit4]   2> 1623441 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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=44, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=48.7080078125, 
floorSegmentMB=0.4580078125, forceMergeDeletesPctAllowed=10.275896221749674, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11348029177293527
   [junit4]   2> 1623442 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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@68cb2e83 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30d26028),segFN=segments_1,generation=1}
   [junit4]   2> 1623442 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1623445 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51672_ 
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-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1/data/
   [junit4]   2> 1623445 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/control-001/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 1623446 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 1623446 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 1623446 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1623446 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 1623446 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1623446 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623446 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623446 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623447 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623447 WARN  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623449 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1623450 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1623450 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1623451 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1623454 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
   [junit4]   2> 1623454 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623455 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623455 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623456 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1623456 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1623456 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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=41, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=1.6982421875, 
floorSegmentMB=1.73828125, forceMergeDeletesPctAllowed=9.154528354456025, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1623457 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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@68cb2e83 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30d26028),segFN=segments_1,generation=1}
   [junit4]   2> 1623457 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1623457 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1b4f0a48[collection1] main]
   [junit4]   2> 1623457 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623459 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623459 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623460 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1623460 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1623460 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623460 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623460 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623461 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1623461 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1623461 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623461 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623461 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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> 1623461 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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 1529055615966511104
   [junit4]   2> 1623463 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ 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 
1529055615966511104
   [junit4]   2> 1623463 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1623464 INFO  
(coreLoadExecutor-5119-thread-1-processing-n:127.0.0.1:51672_) 
[n:127.0.0.1:51672_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1623464 INFO  
(searcherExecutor-5120-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
[collection1] Registered new searcher Searcher@1b4f0a48[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1623465 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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:51672 
collection:control_collection shard:shard1
   [junit4]   2> 1623466 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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> 1623469 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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/95565975869587462-core_node1-n_0000000000
   [junit4]   2> 1623470 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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> 1623471 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] 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> 1623473 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1623474 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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> 1623474 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:51672/collection1/
   [junit4]   2> 1623474 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 1623474 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:51672/collection1/ has no replicas
   [junit4]   2> 1623475 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1623479 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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/95565975869587462-core_node1-n_0000000000
   [junit4]   2> 1623483 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] 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:51672";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 1623488 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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:51672/collection1/ shard1
   [junit4]   2> 1623622 INFO  
(zkCallback-1553-thread-1-processing-n:127.0.0.1:51672_) [n:127.0.0.1:51672_    
] 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> 1623640 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1623645 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
We are http://127.0.0.1:51672/collection1/ and leader is 
http://127.0.0.1:51672/collection1/
   [junit4]   2> 1623645 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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:51672
   [junit4]   2> 1623645 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 1623645 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
publishing state=active
   [junit4]   2> 1623645 INFO  
(coreZkRegister-5113-thread-1-processing-n:127.0.0.1:51672_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51672_ 
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> 1623647 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1623647 INFO  (zkCallback-1555-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7e57aed6 
name:ZooKeeperConnection Watcher:127.0.0.1:56014/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1623647 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1623648 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1623648 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1623649 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] 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:51672";,
   [junit4]   2>   "node_name":"127.0.0.1:51672_",
   [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> 1623650 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1623651 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] 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:51672";,
   [junit4]   2>   "node_name":"127.0.0.1:51672_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1623651 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 1623651 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1623651 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1623653 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1623654 INFO  (zkCallback-1556-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5689d0e1 
name:ZooKeeperConnection Watcher:127.0.0.1:56014/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1623654 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1623654 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1623657 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 2
   [junit4]   2> 1623657 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
collection1
   [junit4]   2> 1623662 INFO  
(zkCallback-1553-thread-1-processing-n:127.0.0.1:51672_) [n:127.0.0.1:51672_    
] 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> 1623662 INFO  (zkCallback-1555-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> 1623768 INFO  
(OverseerStateUpdate-95565975869587462-127.0.0.1:51672_-n_0000000000) 
[n:127.0.0.1:51672_    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/collection1/state.json
   [junit4]   2> 1623786 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001/cores/collection1
   [junit4]   2> 1623787 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001
   [junit4]   2> 1623788 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1623796 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@39458b06{/,null,AVAILABLE}
   [junit4]   2> 1623796 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@58e261d8{HTTP/1.1,[http/1.1]}{127.0.0.1:52666}
   [junit4]   2> 1623796 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.e.j.s.Server Started @1626890ms
   [junit4]   2> 1623797 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/tempDir-001/jetty1,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=52666, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001/cores}
   [junit4]   2> 1623797 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 1623797 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001'
   [junit4]   2> 1623797 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1623797 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1623797 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1623808 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1623811 INFO  (zkCallback-1557-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@52ce9478 
name:ZooKeeperConnection Watcher:127.0.0.1:56014/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1623811 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1623812 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1623813 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1623813 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001/solr.xml
   [junit4]   2> 1623820 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001/cores
   [junit4]   2> 1623820 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1510970874
   [junit4]   2> 1623820 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001]
   [junit4]   2> 1623821 WARN  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_733551967ABECAD3-001/shard-1-001/lib
   [junit4]   2> 1623821 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[733551967ABECAD3]) [    ] 
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> 1623

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

testIndex(IndexFetcher.java:270)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275)
   [junit4]   2> 
   [junit4]   2> 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]   2>        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275)
   [junit4]   2> 
   [junit4]   2> 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]   2>        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275)
   [junit4]   2> 
   [junit4]   2> 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]   2>        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275)
   [junit4]   2> 
   [junit4]   2> 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]   2>        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275)
   [junit4]   2> 
   [junit4]   2> 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]   2>        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275)
   [junit4]   2> 
   [junit4]   2> 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]   2>        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275)
   [junit4]   2> 
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): 
{name=PostingsFormat(name=Memory doPackFST= true), 
id=PostingsFormat(name=Memory doPackFST= true), 
newname=PostingsFormat(name=Memory doPackFST= true)}, docValues:{}, 
maxPointsInLeafNode=222, maxMBSortInHeap=4.07381246934092, 
sim=ClassicSimilarity, locale=ar-SD, timezone=Etc/GMT+2
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_66 (64-bit)/cpus=4,threads=1,free=168905752,total=531103744
   [junit4]   2> NOTE: All tests run in this JVM: [TestCloudInspectUtil, 
BigEndianAscendingWordSerializerTest, CdcrReplicationDistributedZkTest, 
TestMergePolicyConfig, TestDocSet, TestCSVLoader, ExternalFileFieldSortTest, 
XsltUpdateRequestHandlerTest, TlogReplayBufferedWhileIndexingTest, 
TestSolrCLIRunExample, ResponseLogComponentTest, TestInitParams, 
TestDFISimilarityFactory, TestAddFieldRealTimeGet, JSONWriterTest, 
RecoveryAfterSoftCommitTest, TestStressLucene, TestCopyFieldCollectionResource, 
DataDrivenBlockJoinTest, PrimUtilsTest, TestChildDocTransformer, DateFieldTest, 
TestSolrXml, TestJoin, DocValuesMissingTest, TestConfigSetsAPI, 
TestDynamicFieldResource, TestExactSharedStatsCache, BlockJoinFacetRandomTest, 
DeleteReplicaTest, TestOmitPositions, CachingDirectoryFactoryTest, 
TestMiniSolrCloudClusterSSL, SharedFSAutoReplicaFailoverUtilsTest, 
TestSchemaManager, TestSerializedLuceneMatchVersion, TestReloadDeadlock, 
TestLuceneMatchVersion, TestAtomicUpdateErrorCases, URLClassifyProcessorTest, 
ResourceLoaderTest, AsyncCallRequestStatusResponseTest, MergeStrategyTest, 
TestSolr4Spatial, DistributedFacetPivotSmallTest, SparseHLLTest, 
HdfsChaosMonkeySafeLeaderTest, TestBinaryField, CurrencyFieldOpenExchangeTest, 
AddBlockUpdateTest, TestPseudoReturnFields, TestFieldTypeCollectionResource, 
CoreMergeIndexesAdminHandlerTest, TestTrackingShardHandlerFactory, 
TestCryptoKeys, DistributedSpellCheckComponentTest, ResponseHeaderTest, 
TestLazyCores, TestRealTimeGet, TestRawResponseWriter, 
DisMaxRequestHandlerTest, CursorPagingTest, HdfsDirectoryFactoryTest, 
TestDynamicLoading, CustomCollectionTest, SolrXmlInZkTest, TestFaceting, 
AnalyticsMergeStrategyTest, SystemInfoHandlerTest, DistributedQueueTest, 
TestSchemaResource, CollectionTooManyReplicasTest, TestBlendedInfixSuggestions, 
TestFoldingMultitermQuery, DistributedFacetPivotLargeTest, OverseerRolesTest, 
SmileWriterTest, TestCrossCoreJoin, SyncSliceTest, TestRandomDVFaceting, 
SegmentsInfoRequestHandlerTest, HdfsUnloadDistributedZkTest, IndexSchemaTest, 
TestLMDirichletSimilarityFactory, CacheHeaderTest, 
TestSolrConfigHandlerConcurrent, TestComplexPhraseQParserPlugin, 
TestIBSimilarityFactory, TestComponentsName, 
LeaderInitiatedRecoveryOnShardRestartTest, CloudMLTQParserTest, 
BasicAuthIntegrationTest, TestHdfsUpdateLog, TestRangeQuery, 
TestMaxScoreQueryParser, EchoParamsTest, TestReplicationHandler]
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestReplicationHandler -Dtests.seed=733551967ABECAD3 
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=ar-SD -Dtests.timezone=Etc/GMT+2 -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J0 | TestReplicationHandler (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ObjectTracker found 10 
object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, 
NRTCachingDirectory, NRTCachingDirectory]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([733551967ABECAD3]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:238)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [441/580 (3!)] on J0 in 682.94s, 13 tests, 1 failure <<< 
FAILURES!

[...truncated 474 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:747:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:684:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:59:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/common-build.xml:526:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1457:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1014:
 There were test failures: 580 suites (10 ignored), 2318 tests, 1 suite-level 
error, 2 errors, 32 ignored (16 assumptions) [seed: 733551967ABECAD3]

Total time: 212 minutes 30 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