lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-5.5 - Build # 5 - Still Failing
Date Sat, 10 Sep 2016 00:09:05 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/5/

6 tests failed.
FAILED:  org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.test

Error Message:
Exactly one shard should have changed, instead: [shard2, shard1] nodes=([core_node2(shard1), core_node4(shard1), core_node3(shard2)]) expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: Exactly one shard should have changed, instead: [shard2, shard1] nodes=([core_node2(shard1), core_node4(shard1), core_node3(shard2)]) expected:<1> but was:<2>
	at __randomizedtesting.SeedInfo.seed([D6A8934F3CFE4FB5:5EFCAC959202224D]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.test(DistribDocExpirationUpdateProcessorTest.java:119)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	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)


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest

Error Message:
3 threads leaked from SUITE scope at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest:     1) Thread[id=43709, name=searcherExecutor-9453-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:745)    2) Thread[id=43724, name=searcherExecutor-9421-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:745)    3) Thread[id=44215, name=searcherExecutor-9719-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 3 threads leaked from SUITE scope at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest: 
   1) Thread[id=43709, name=searcherExecutor-9453-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=43724, name=searcherExecutor-9421-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
   3) Thread[id=44215, name=searcherExecutor-9719-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
	at __randomizedtesting.SeedInfo.seed([D6A8934F3CFE4FB5]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest

Error Message:
There are still zombie threads that couldn't be terminated:    1) Thread[id=43709, name=searcherExecutor-9453-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:745)    2) Thread[id=43724, name=searcherExecutor-9421-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:745)    3) Thread[id=44215, name=searcherExecutor-9719-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   1) Thread[id=43709, name=searcherExecutor-9453-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=43724, name=searcherExecutor-9421-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
   3) Thread[id=44215, name=searcherExecutor-9719-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
	at __randomizedtesting.SeedInfo.seed([D6A8934F3CFE4FB5]:0)


FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh

Error Message:
Could not find collection : c1

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
	at __randomizedtesting.SeedInfo.seed([D6A8934F3CFE4FB5:C912E2B8EC9E8970]:0)
	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:136)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:42)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	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 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)


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

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:37946
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:586)
	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:400)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:516)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:179)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	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.read(SocketInputStream.java:152)
	at java.net.SocketInputStream.read(SocketInputStream.java:122)
	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.CollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=15488, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=15488, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38471/l/jn: collection already exists: awholynewstresscollection_collection3_0
	at __randomizedtesting.SeedInfo.seed([D6A8934F3CFE4FB5]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577)
	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.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1121)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:891)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:827)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1575)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1596)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:984)




Build Log:
[...truncated 12031 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/init-core-data-001
   [junit4]   2> 2878475 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[D6A8934F3CFE4FB5]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 2878475 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[D6A8934F3CFE4FB5]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /l/jn
   [junit4]   2> 2878480 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2878482 INFO  (Thread-8567) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2878482 INFO  (Thread-8567) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2878598 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.ZkTestServer start zk server on port:48649
   [junit4]   2> 2878598 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2878599 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2878618 INFO  (zkCallback-1300-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11dfa0 name:ZooKeeperConnection Watcher:127.0.0.1:48649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2878618 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2878618 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2878618 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2878621 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2878630 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2878630 INFO  (zkCallback-1301-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4571b64d name:ZooKeeperConnection Watcher:127.0.0.1:48649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2878630 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2878631 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2878631 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 2878632 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 2878633 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 2878634 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 2878635 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 2878635 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2878637 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 2878637 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 2878639 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2878639 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2878640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 2878640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2878642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 2878642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2878650 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 2878651 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 2878652 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2878652 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2878653 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2878653 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2878654 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2878654 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2878655 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2878655 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2878657 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 2878657 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2878668 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2878679 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2878682 INFO  (zkCallback-1302-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34396be9 name:ZooKeeperConnection Watcher:127.0.0.1:48649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2878682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2878683 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2878683 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2878690 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2878710 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2878711 INFO  (zkCallback-1303-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@525ac59f name:ZooKeeperConnection Watcher:127.0.0.1:48649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2878711 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2878711 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2878712 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 2878712 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 2878714 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 2878718 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 2878719 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 2878720 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 2878721 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2878721 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2878722 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 2878722 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 2878724 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 2878724 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 2878725 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 2878725 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 2878726 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 2878726 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 2878727 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2878727 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2878728 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 2878729 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 2878734 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 2878734 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 2878735 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 2878735 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 2878845 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1
   [junit4]   2> 2878862 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 2878872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6621224a{/l/jn,null,AVAILABLE}
   [junit4]   2> 2878872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.e.j.s.ServerConnector Started ServerConnector@9525ca8{HTTP/1.1}{127.0.0.1:38471}
   [junit4]   2> 2878872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.e.j.s.Server Started @2883197ms
   [junit4]   2> 2878872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=38471, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores, hostContext=/l/jn, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/tempDir-001/control/data}
   [junit4]   2> 2878872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@3836b1bb
   [junit4]   2> 2878873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001'
   [junit4]   2> 2878873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2878873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2878873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2878874 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2878895 INFO  (zkCallback-1304-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7ae97da4 name:ZooKeeperConnection Watcher:127.0.0.1:48649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2878896 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2878896 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2878897 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2878897 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/solr.xml
   [junit4]   2> 2878909 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores
   [junit4]   2> 2878909 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.CoreContainer New CoreContainer 356317489
   [junit4]   2> 2878909 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001]
   [junit4]   2> 2878909 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/lib
   [junit4]   2> 2878909 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] 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,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
   [junit4]   2> 2878912 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2878913 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2878913 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2878913 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48649/solr
   [junit4]   2> 2878913 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2878913 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2878918 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2878918 INFO  (zkCallback-1307-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30b7d817 name:ZooKeeperConnection Watcher:127.0.0.1:48649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2878918 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2878919 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2878946 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@30e5be49
   [junit4]   2> 2878947 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2878958 INFO  (zkCallback-1308-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@576ae7f7 name:ZooKeeperConnection Watcher:127.0.0.1:48649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2878958 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2878966 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 2878990 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 2879000 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 2879002 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 2879003 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 2879008 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2879009 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2879010 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2879011 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2879011 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38471_l%2Fjn
   [junit4]   2> 2879012 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:38471_l%2Fjn
   [junit4]   2> 2879134 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 2879166 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 2879182 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2879183 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96564783194701830-127.0.0.1:38471_l%2Fjn-n_0000000000
   [junit4]   2> 2879183 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38471_l%2Fjn
   [junit4]   2> 2879183 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 2879184 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.Overseer Overseer (id=96564783194701830-127.0.0.1:38471_l%2Fjn-n_0000000000) starting
   [junit4]   2> 2879185 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 2879191 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 2879194 INFO  (OverseerCollectionConfigSetProcessor-96564783194701830-127.0.0.1:38471_l%2Fjn-n_0000000000) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 2879195 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2879199 INFO  (OverseerStateUpdate-96564783194701830-127.0.0.1:38471_l%2Fjn-n_0000000000) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 2879247 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 2879247 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2879248 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores
   [junit4]   2> 2879248 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {coreNodeName=, loadOnStartup=true, collection=control_collection, configSetProperties=configsetprops.json, schema=schema.xml, config=solrconfig.xml, shard=, dataDir=data/, transient=false, name=collection1}
   [junit4]   2> 2879248 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1
   [junit4]   2> 2879248 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 2879262 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1
   [junit4]   2> 2879262 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D6A8934F3CFE4FB5]) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2879266 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 2879266 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 2879290 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible
   [junit4]   2> 2879290 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [control_collection]
   [junit4]   2> 2879291 INFO  (OverseerStateUpdate-96564783194701830-127.0.0.1:38471_l%2Fjn-n_0000000000) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:38471_l%2Fjn",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:38471/l/jn",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "state":"down"} current state version: 0
   [junit4]   2> 2879302 INFO  (OverseerStateUpdate-96564783194701830-127.0.0.1:38471_l%2Fjn-n_0000000000) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:38471_l%2Fjn",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:38471/l/jn",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "state":"down"}
   [junit4]   2> 2879302 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 2879302 INFO  (OverseerStateUpdate-96564783194701830-127.0.0.1:38471_l%2Fjn-n_0000000000) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 2879310 INFO  (OverseerStateUpdate-96564783194701830-127.0.0.1:38471_l%2Fjn-n_0000000000) [n:127.0.0.1:38471_l%2Fjn    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2879318 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 2879334 INFO  (zkCallback-1308-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn    ] 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> 2880326 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2880326 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 2880333 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 2880333 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 2880338 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 2880338 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1'
   [junit4]   2> 2880338 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2880338 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2880340 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 2880344 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2880409 WARN  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2880424 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.3
   [junit4]   2> 2880483 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2880497 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2881050 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2881058 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2881059 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2881069 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2881071 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2881073 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2881074 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2881075 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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-5.5/checkout/solr/build/solr-core/test/J1
   [junit4]   2> 2881075 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 2881075 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2881076 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1/data/]
   [junit4]   2> 2881076 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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@70f44ad5
   [junit4]   2> 2881076 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1/data
   [junit4]   2> 2881076 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1/data/index/
   [junit4]   2> 2881076 WARN  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 2881077 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1/data/index
   [junit4]   2> 2881077 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.88671875, noCFSRatio=0.251538182541141]
   [junit4]   2> 2881077 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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@5ea89902 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ed582db),segFN=segments_1,generation=1}
   [junit4]   2> 2881077 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2881080 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 2881080 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2881080 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2881080 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2881080 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2881080 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881081 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881081 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881081 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881081 WARN  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881082 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:38471_l%2Fjn 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-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1/data/
   [junit4]   2> 2881082 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D6A8934F3CFE4FB5-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 2881082 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2881083 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2881084 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2881085 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2881087 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/plugins,/admin/ping,/dump,/admin/properties,/config,/replication,/admin/luke,/update,/admin/logging,standard,/update/json,/admin/mbeans,/admin/system,/admin/segments,/admin/file,/update/csv,/update/json/docs,/admin/threads,/get,/schema
   [junit4]   2> 2881089 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881089 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881089 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881090 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2881090 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2881090 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2881090 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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@5ea89902 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ed582db),segFN=segments_1,generation=1}
   [junit4]   2> 2881090 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2881091 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2ed73866[collection1] main
   [junit4]   2> 2881091 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881091 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881092 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881092 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2881092 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 2881092 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881092 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881092 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881092 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2881093 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2881093 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881093 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881093 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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> 2881093 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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 1545036533852012544
   [junit4]   2> 2881095 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn 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 1545036533852012544
   [junit4]   2> 2881095 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 2881095 INFO  (coreLoadExecutor-4386-thread-1-processing-n:127.0.0.1:38471_l%2Fjn) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2881096 INFO  (searcherExecutor-4387-thread-1-processing-n:127.0.0.1:38471_l%2Fjn c:control_collection x:collection1 r:core_node1 s:shard1) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ed73866[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2881096 INFO  (coreZkRegister-4380-thread-1-processing-n:127.0.0.1:38471_l%2Fjn c:control_collection x:collection1 r:core_node1 s:shard1) [n:127.0.0.1:38471_l%2Fjn 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:38471/l/jn collection:control_collection shard:shard1
   [junit4]   2> 2881097 INFO  (coreZkRegister-4380-thread-1-processing-n:127.0.0.1:38471_l%2Fjn c:control_collection x:collection1 r:core_node1 s:shard1) [n:127.0.0.1:38471_l%2Fjn c:control_collection s:s

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

o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 6145572 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 6145576 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 6145576 INFO  (zkCallback-26987-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30b345cc name:ZooKeeperConnection Watcher:127.0.0.1:46543 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 6145576 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 6145576 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 6145576 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 6145580 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 6145581 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 6145581 INFO  (zkCallback-26988-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@67df1a42 name:ZooKeeperConnection Watcher:127.0.0.1:46543/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 6145581 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 6145582 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 6145582 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 6145583 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 6145583 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 6145591 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 6145592 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 6145593 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 6145594 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 6145594 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 6145594 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 6145596 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 6145612 INFO  (zkCallback-26988-thread-2) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 6145612 INFO  (zkCallback-26988-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
   [junit4]   2> 6145623 INFO  (zkCallback-26988-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 6145677 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46543 46543
   [junit4]   2> 6145678 INFO  (Thread-61721) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46543 46543
   [junit4]   2> 6145687 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D6A8934F3CFE4FB5]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 6145687 INFO  (SUITE-ZkStateReaderTest-seed#[D6A8934F3CFE4FB5]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_D6A8934F3CFE4FB5-001
   [junit4]   2> Sep 09, 2016 11:48:45 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=DefaultSimilarity, locale=es-CL, timezone=Pacific/Efate
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=166186600,total=520617984
   [junit4]   2> NOTE: All tests run in this JVM: [DistributedQueryComponentCustomSortTest, TestExactSharedStatsCache, DirectoryFactoryTest, LeaderElectionIntegrationTest, CleanupOldIndexTest, TestRecoveryHdfs, PeerSyncTest, RequestLoggingTest, RecoveryAfterSoftCommitTest, TestBinaryField, BasicAuthIntegrationTest, DefaultValueUpdateProcessorTest, OverseerStatusTest, TestFieldResource, TestPseudoReturnFields, TestClassNameShortening, TestAnalyzedSuggestions, TestConfigSetProperties, SynonymTokenizerTest, TestRebalanceLeaders, TestRandomCollapseQParserPlugin, TestNRTOpen, CurrencyFieldOpenExchangeTest, TestXmlQParser, TestCryptoKeys, MergeStrategyTest, SolrCoreTest, DateFieldTest, ZkNodePropsTest, TestSolrQueryResponse, AsyncCallRequestStatusResponseTest, TestOrdValues, TestMiniSolrCloudClusterSSL, TestIntervalFaceting, TestOmitPositions, TestBlendedInfixSuggestions, IndexSchemaTest, AnalyticsMergeStrategyTest, HdfsRecoverLeaseTest, RAMDirectoryFactoryTest, TestRandomFaceting, TestMergePolicyConfig, TestMissingGroups, ChangedSchemaMergeTest, TestHashQParserPlugin, QueryElevationComponentTest, SharedFSAutoReplicaFailoverUtilsTest, TestPKIAuthenticationPlugin, TestFoldingMultitermQuery, TestDistributedSearch, RulesTest, TestSolrConfigHandlerCloud, HdfsSyncSliceTest, TestArbitraryIndexDir, TestCSVLoader, TestIBSimilarityFactory, DeleteInactiveReplicaTest, TestPhraseSuggestions, TestRangeQuery, TestImplicitCoreProperties, CoreAdminRequestStatusTest, TestLuceneMatchVersion, DistributedFacetPivotSmallAdvancedTest, ResourceLoaderTest, TestSolrConfigHandler, AlternateDirectoryTest, ConnectionReuseTest, TestRealTimeGet, TestReplicationHandler, TestCustomDocTransformer, EnumFieldTest, OutputWriterTest, CacheHeaderTest, SolrXmlInZkTest, TestRemoveLastDynamicCopyField, TestPivotHelperCode, DeleteShardTest, TestLazyCores, RequiredFieldsTest, TestCollationFieldDocValues, PreAnalyzedFieldTest, SignatureUpdateProcessorFactoryTest, CopyFieldTest, TestDFISimilarityFactory, JSONWriterTest, EchoParamsTest, XsltUpdateRequestHandlerTest, ExplicitHLLTest, TestConfig, PrimUtilsTest, TestDynamicFieldCollectionResource, ActionThrottleTest, ExternalFileFieldSortTest, DistribJoinFromCollectionTest, LeaderFailoverAfterPartitionTest, MigrateRouteKeyTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, PeerSyncReplicationTest, RollingRestartTest, TestConfigSetsAPI, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestOnReconnectListenerSupport, TestReplicaProperties, TestStressLiveNodes, HdfsNNFailoverTest, HdfsWriteToMultipleCollectionsTest, ZkStateReaderTest]
   [junit4] Completed [484/575 (4!)] on J0 in 2.66s, 6 tests, 1 error <<< FAILURES!

[...truncated 308 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:757: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/solr/common-build.xml:534: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/checkout/lucene/common-build.xml:1033: There were test failures: 575 suites (7 ignored), 2322 tests, 2 suite-level errors, 3 errors, 1 failure, 383 ignored (16 assumptions) [seed: D6A8934F3CFE4FB5]

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




Mime
View raw message