Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/16/

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

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting 
response from server at: http://127.0.0.1:38483/nvwv
        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:  
junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest

Error Message:
2 threads leaked from SUITE scope at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest:     1) Thread[id=104550, 
name=searcherExecutor-10263-thread-1, state=WAITING, 
group=TGRP-CollectionsAPIDistributedZkTest]         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=104547, 
name=searcherExecutor-10258-thread-1, state=WAITING, 
group=TGRP-CollectionsAPIDistributedZkTest]         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: 2 threads leaked from SUITE 
scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest: 
   1) Thread[id=104550, name=searcherExecutor-10263-thread-1, state=WAITING, 
group=TGRP-CollectionsAPIDistributedZkTest]
        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=104547, name=searcherExecutor-10258-thread-1, state=WAITING, 
group=TGRP-CollectionsAPIDistributedZkTest]
        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([FF53D941DD25D8C8]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest

Error Message:
There are still zombie threads that couldn't be terminated:    1) 
Thread[id=104550, name=searcherExecutor-10263-thread-1, state=WAITING, 
group=TGRP-CollectionsAPIDistributedZkTest]         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=104547, 
name=searcherExecutor-10258-thread-1, state=WAITING, 
group=TGRP-CollectionsAPIDistributedZkTest]         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=104550, name=searcherExecutor-10263-thread-1, state=WAITING, 
group=TGRP-CollectionsAPIDistributedZkTest]
        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=104547, name=searcherExecutor-10258-thread-1, state=WAITING, 
group=TGRP-CollectionsAPIDistributedZkTest]
        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([FF53D941DD25D8C8]:0)


FAILED:  org.apache.solr.cloud.RollingRestartTest.test

Error Message:
Unable to restart (#3): CloudJettyRunner 
[url=http://127.0.0.1:33810/collection1]

Stack Trace:
java.lang.AssertionError: Unable to restart (#3): CloudJettyRunner 
[url=http://127.0.0.1:33810/collection1]
        at 
__randomizedtesting.SeedInfo.seed([FF53D941DD25D8C8:7707E69B73D9B530]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:103)
        at 
org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:52)
        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:  org.apache.solr.handler.TestReplicationHandler.doTestStressReplication

Error Message:
timed out waiting for collection1 startAt time to exceed: Tue Jun 21 00:27:10 
BRT 2016

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to 
exceed: Tue Jun 21 00:27:10 BRT 2016
        at 
__randomizedtesting.SeedInfo.seed([FF53D941DD25D8C8:24F8D987D80DB17B]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1501)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:853)
        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)




Build Log:
[...truncated 11306 lines...]
   [junit4] Suite: org.apache.solr.cloud.RollingRestartTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/init-core-data-001
   [junit4]   2> 182045 INFO  
(SUITE-RollingRestartTest-seed#[FF53D941DD25D8C8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 182045 INFO  
(SUITE-RollingRestartTest-seed#[FF53D941DD25D8C8]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 182049 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 182059 INFO  (Thread-319) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 182059 INFO  (Thread-319) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 182159 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:46923
   [junit4]   2> 182159 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 182171 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 182186 INFO  (zkCallback-90-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@3eb25b6c 
name:ZooKeeperConnection Watcher:127.0.0.1:46923 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 182186 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 182186 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 182186 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 182189 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 182190 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 182190 INFO  (zkCallback-91-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@71da0d2e 
name:ZooKeeperConnection Watcher:127.0.0.1:46923/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 182190 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 182190 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 182190 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 182192 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 182193 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 182194 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 182195 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 182195 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 182197 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 182197 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 182198 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 182199 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 182200 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 182200 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 182201 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 182201 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 182202 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 182202 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 182203 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 182204 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 182205 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 182205 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 182206 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 182206 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 182207 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 182207 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 182208 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 182208 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 182305 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1
   [junit4]   2> 182307 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] o.e.j.s.Server 
jetty-9.2.13.v20150730
   [junit4]   2> 182309 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@760822c{/,null,AVAILABLE}
   [junit4]   2> 182309 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@51021a7c{HTTP/1.1}{127.0.0.1:46656}
   [junit4]   2> 182310 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] o.e.j.s.Server 
Started @184623ms
   [junit4]   2> 182310 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, 
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/tempDir-001/control/data,
 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores,
 hostPort=46656}
   [junit4]   2> 182310 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 182310 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001'
   [junit4]   2> 182310 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 182310 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 182310 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 182327 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 182339 INFO  (zkCallback-92-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@243702cd 
name:ZooKeeperConnection Watcher:127.0.0.1:46923/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 182339 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 182339 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 182343 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 182343 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/solr.xml
   [junit4]   2> 182353 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores
   [junit4]   2> 182354 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1806851248
   [junit4]   2> 182354 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001]
   [junit4]   2> 182354 WARN  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/lib
   [junit4]   2> 182354 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 182355 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 182355 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 182355 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 182355 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46923/solr
   [junit4]   2> 182355 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 182355 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 182356 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 182357 INFO  (zkCallback-95-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@325d8b7f 
name:ZooKeeperConnection Watcher:127.0.0.1:46923 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 182357 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 182357 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 182358 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.ZkController Added new OnReconnect listener 
org.apache.solr.cloud.ZkController$6@35e04a34
   [junit4]   2> 182361 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 182361 INFO  
(zkCallback-96-thread-1-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@125c4e0f 
name:ZooKeeperConnection Watcher:127.0.0.1:46923/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 182361 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 182367 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 182368 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 182371 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 182372 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 182425 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 182427 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 182428 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 182430 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 182432 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 182433 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46656_
   [junit4]   2> 182433 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46656_
   [junit4]   2> 182435 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 182436 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 182437 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 182438 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/overseer_elect/election/96107083774754820-127.0.0.1:46656_-n_0000000000
   [junit4]   2> 182438 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46656_
   [junit4]   2> 182438 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 182439 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.Overseer Overseer (id=96107083774754820-127.0.0.1:46656_-n_0000000000) 
starting
   [junit4]   2> 182441 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 182446 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=30000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 182447 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 182448 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 182455 INFO  
(OverseerCollectionConfigSetProcessor-96107083774754820-127.0.0.1:46656_-n_0000000000)
 [n:127.0.0.1:46656_    ] o.a.s.c.OverseerTaskProcessor Process current queue 
of overseer operations
   [junit4]   2> 182459 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 182478 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 182478 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 182479 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores
   [junit4]   2> 182479 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.CoreDescriptor Created CoreDescriptor: {collection=control_collection, 
name=collection1, configSetProperties=configsetprops.json, schema=schema.xml, 
config=solrconfig.xml, dataDir=data/, loadOnStartup=true, transient=false, 
coreNodeName=, shard=}
   [junit4]   2> 182479 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1
   [junit4]   2> 182480 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 182484 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 182484 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 182485 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 182485 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader addZkWatch [control_collection]
   [junit4]   2> 182486 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 182487 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 182491 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "state":"down",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:46656_",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46656"} current state version: 0
   [junit4]   2> 182492 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0
   [junit4]   2> 182492 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:46656_    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 182492 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "state":"down",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:46656_",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46656"}
   [junit4]   2> 182492 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
control_collection
   [junit4]   2> 182493 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 182494 INFO  
(zkCallback-96-thread-1-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
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> 182495 INFO  
(zkCallback-96-thread-2-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
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> 183509 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 183509 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:control_collection
   [junit4]   2> 183510 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 183510 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config from: 
[/collections/control_collection]
   [junit4]   2> 183510 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=[/collections/control_collection] 
[configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 183510 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1'
   [junit4]   2> 183511 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 183511 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 183513 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 183527 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 183535 WARN  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
instead.
   [junit4]   2> 183537 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 5.5.2
   [junit4]   2> 183588 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 183602 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 183885 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 183898 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
default search field in schema is text
   [junit4]   2> 183899 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 184109 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 184113 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 184119 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 184122 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 184125 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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/solr/build/solr-core/test/J0
   [junit4]   2> 184125 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
control_collection
   [junit4]   2> 184125 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore solr.StandardDirectoryFactory
   [junit4]   2> 184129 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1/data/]
   [junit4]   2> 184129 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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@ecab30c
   [junit4]   2> 184139 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1/data
   [junit4]   2> 184140 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1/data/index/
   [junit4]   2> 184140 WARN  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 184141 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1/data/index
   [junit4]   2> 184141 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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=5, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7181942841187283]
   [junit4]   2> 184147 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        
commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 184147 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 184163 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 184167 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46656_ 
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/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1/data/
   [junit4]   2> 184171 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 184171 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 184171 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 184171 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 184171 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184172 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184172 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184172 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184172 WARN  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184174 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 184175 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 184176 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 184177 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 184183 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.RequestHandlers Registered paths: 
/admin/ping,/admin/file,/dump,/get,/schema,/admin/mbeans,/config,/admin/properties,/admin/system,/admin/segments,/replication,/update/json,standard,/update,/admin/luke,/admin/plugins,/update/csv,/update/json/docs,/admin/logging,/admin/threads
   [junit4]   2> 184184 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184184 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184184 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184185 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 184185 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 184185 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@7bf4664a
   [junit4]   2> 184186 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        
commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/control-001/cores/collection1/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 184186 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 184186 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@1a1051de[collection1] main
   [junit4]   2> 184186 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184187 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184187 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184187 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 184187 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 184187 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184187 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184187 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184187 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 184188 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 184188 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184188 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184188 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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> 184188 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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 1537713342626398208
   [junit4]   2> 184190 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ 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 
1537713342626398208
   [junit4]   2> 184190 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 184190 INFO  
(coreLoadExecutor-531-thread-1-processing-n:127.0.0.1:46656_) 
[n:127.0.0.1:46656_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 184191 INFO  
(searcherExecutor-532-thread-1-processing-c:control_collection 
n:127.0.0.1:46656_ x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
[collection1] Registered new searcher Searcher@1a1051de[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 184192 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ 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:46656 collection:control_collection 
shard:shard1
   [junit4]   2> 184192 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: 
/collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 184196 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership 
election with path: 
/collections/control_collection/leader_elect/shard1/election/96107083774754820-core_node1-n_0000000000
   [junit4]   2> 184196 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running 
the leader process for shard=shard1 and weAreReplacement=false and 
leaderVoteWait=10000
   [junit4]   2> 184198 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection"} current state version: 1
   [junit4]   2> 184201 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough 
replicas found to continue.
   [junit4]   2> 184201 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may 
be the new leader - try and sync
   [junit4]   2> 184201 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:46656/collection1/
   [junit4]   2> 184201 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now 
sync replicas to me
   [junit4]   2> 184201 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:46656/collection1/ has no replicas
   [junit4]   2> 184201 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: 
/collections/control_collection/leaders/shard1
   [junit4]   2> 184203 INFO  
(zkCallback-96-thread-3-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
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> 184203 INFO  
(zkCallback-96-thread-4-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
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> 184204 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase 
Creating leader registration node 
/collections/control_collection/leaders/shard1/leader after winning as 
/collections/control_collection/leader_elect/shard1/election/96107083774754820-core_node1-n_0000000000
   [junit4]   2> 184206 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am 
the new leader: http://127.0.0.1:46656/collection1/ shard1
   [junit4]   2> 184206 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:46656";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 2
   [junit4]   2> 184311 INFO  
(zkCallback-96-thread-3-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
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> 184311 INFO  
(zkCallback-96-thread-4-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
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> 184361 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are 
http://127.0.0.1:46656/collection1/ and leader is 
http://127.0.0.1:46656/collection1/
   [junit4]   2> 184361 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed 
for core=collection1 baseURL=http://127.0.0.1:46656
   [junit4]   2> 184361 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no 
recovery necessary
   [junit4]   2> 184361 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing 
state=active
   [junit4]   2> 184361 INFO  
(coreZkRegister-525-thread-1-processing-c:control_collection n:127.0.0.1:46656_ 
x:collection1 s:shard1 r:core_node1) [n:127.0.0.1:46656_ c:control_collection 
s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found 
on descriptor - reading it from system property
   [junit4]   2> 184363 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "state":"active",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "node_name":"127.0.0.1:46656_",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "base_url":"http://127.0.0.1:46656"} current state version: 3
   [junit4]   2> 184364 INFO  
(OverseerStateUpdate-96107083774754820-127.0.0.1:46656_-n_0000000000) 
[n:127.0.0.1:46656_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "state":"active",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "node_name":"127.0.0.1:46656_",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "base_url":"http://127.0.0.1:46656"}
   [junit4]   2> 184466 INFO  
(zkCallback-96-thread-2-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
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> 184466 INFO  
(zkCallback-96-thread-4-processing-n:127.0.0.1:46656_) [n:127.0.0.1:46656_    ] 
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> 184542 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 184547 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 184548 INFO  (zkCallback-98-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7ad386c9 
name:ZooKeeperConnection Watcher:127.0.0.1:46923/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 184548 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 184548 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 184548 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 184550 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 184613 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001/cores/collection1
   [junit4]   2> 184613 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001
   [junit4]   2> 184616 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] o.e.j.s.Server 
jetty-9.2.13.v20150730
   [junit4]   2> 184627 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@184d645d{/,null,AVAILABLE}
   [junit4]   2> 184627 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@f82222{HTTP/1.1}{127.0.0.1:45677}
   [junit4]   2> 184627 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] o.e.j.s.Server 
Started @186941ms
   [junit4]   2> 184628 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=45677, hostContext=/, 
solrconfig=solrconfig.xml, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001/cores,
 
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/tempDir-001/jetty1}
   [junit4]   2> 184628 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 184628 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001'
   [junit4]   2> 184628 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 184628 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 184628 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 184630 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 184631 INFO  (zkCallback-99-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4fe4351d 
name:ZooKeeperConnection Watcher:127.0.0.1:46923/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 184631 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 184631 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 184632 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 184632 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001/solr.xml
   [junit4]   2> 184641 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001/cores
   [junit4]   2> 184641 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 535152141
   [junit4]   2> 184641 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001]
   [junit4]   2> 184642 WARN  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_FF53D941DD25D8C8-001/shard-1-001/lib
   [junit4]   2> 184644 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 184645 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 184646 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 184646 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 184646 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46923/solr
   [junit4]   2> 184646 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 184646 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 184647 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 184648 INFO  (zkCallback-102-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@68ee2460 
name:ZooKeeperConnection Watcher:127.0.0.1:46923 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 184656 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 184656 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 184666 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:45677_    ] 
o.a.s.c.ZkController Added new OnReconnect listener 
org.apache.solr.cloud.ZkController$6@cab39ef
   [junit4]   2> 184679 INFO  
(TEST-RollingRestartTest.test-seed#[FF53D941DD25D8C8]) [n:127.0.0.1:45677_    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 184691 INFO  
(zkCallback-103-thread-1-processing-n:127.0.0.1:45677_) [n:127.0.

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

til.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:745)
   [junit4]   2>    2) Thread[id=104547, name=searcherExecutor-10258-thread-1, 
state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
   [junit4]   2>         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54), 
sim=DefaultSimilarity, locale=is-IS, timezone=America/Creston
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 
1.7.0_80 (64-bit)/cpus=4,threads=3,free=99903744,total=496500736
   [junit4]   2> NOTE: All tests run in this JVM: [TestStressRecovery, 
SparseHLLTest, TestSearchPerf, TestManagedSynonymFilterFactory, 
TestDynamicFieldResource, ConfigSetsAPITest, SuggesterTSTTest, 
DistanceUnitsTest, BinaryUpdateRequestHandlerTest, DirectUpdateHandlerTest, 
FullHLLTest, TestManagedSchemaFieldResource, TestGroupingSearch, 
TestElisionMultitermQuery, AssignTest, SolrXmlInZkTest, TestTolerantSearch, 
SuggesterTest, CoreMergeIndexesAdminHandlerTest, TestIndexingPerformance, 
AnalyticsQueryTest, TestExtendedDismaxParser, ZkStateReaderTest, 
TestCopyFieldCollectionResource, TestPHPSerializedResponseWriter, 
StatsComponentTest, HdfsBasicDistributedZk2Test, 
TestRuleBasedAuthorizationPlugin, TestReload, TestFieldTypeCollectionResource, 
TestInfoStreamLogging, TestJmxIntegration, FullSolrCloudDistribCmdsTest, 
SpatialRPTFieldTypeTest, TestRestManager, AutoCommitTest, TestSort, 
RecoveryAfterSoftCommitTest, TestStressLiveNodes, 
FieldMutatingUpdateProcessorTest, SolrTestCaseJ4Test, TestScoreJoinQPScore, 
ScriptEngineTest, LeaderElectionTest, LukeRequestHandlerTest, OverseerTest, 
TestSchemaNameResource, TestMacros, TestExclusionRuleCollectionAccess, 
TestStressUserVersions, DistributedFacetPivotSmallTest, NoCacheHeaderTest, 
AnalysisErrorHandlingTest, TestExactStatsCache, 
AddSchemaFieldsUpdateProcessorFactoryTest, TestNoOpRegenerator, 
TestSweetSpotSimilarityFactory, DistributedQueryComponentOptimizationTest, 
PluginInfoTest, SimpleFacetsTest, StatelessScriptUpdateProcessorFactoryTest, 
TestExceedMaxTermLength, TestReloadDeadlock, TestWriterPerf, SuggesterWFSTTest, 
TestInitQParser, CollectionsAPIAsyncDistributedZkTest, TestCoreDiscovery, 
TestSolrIndexConfig, TestDistributedStatsComponentCardinality, 
HighlighterConfigTest, TestRecovery, DateRangeFieldTest, HdfsRecoverLeaseTest, 
TestSolrQueryParser, TestReloadAndDeleteDocs, CircularListTest, 
SegmentsInfoRequestHandlerTest, TestCryptoKeys, TestSolrDynamicMBean, 
PreAnalyzedUpdateProcessorTest, TestPostingsSolrHighlighter, 
WrapperMergePolicyFactoryTest, SolrCoreCheckLockOnStartupTest, TestZkChroot, 
TestStressReorder, SharedFSAutoReplicaFailoverUtilsTest, DistributedQueueTest, 
UpdateParamsTest, DateMathParserTest, DocValuesMultiTest, 
SaslZkACLProviderTest, TestCloudInspectUtil, FieldAnalysisRequestHandlerTest, 
BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, 
UnloadDistributedZkTest, CollectionsAPIDistributedZkTest]
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=CollectionsAPIDistributedZkTest -Dtests.seed=FF53D941DD25D8C8 
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=is-IS -Dtests.timezone=America/Creston -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J2 | CollectionsAPIDistributedZkTest (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE 
scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest: 
   [junit4]    >    1) Thread[id=104550, name=searcherExecutor-10263-thread-1, 
state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    2) Thread[id=104547, name=searcherExecutor-10258-thread-1, 
state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([FF53D941DD25D8C8]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=104550, name=searcherExecutor-10263-thread-1, 
state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    2) Thread[id=104547, name=searcherExecutor-10258-thread-1, 
state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([FF53D941DD25D8C8]:0)
   [junit4] Completed [305/573 (3!)] on J2 in 772.26s, 1 test, 3 errors <<< 
FAILURES!

[...truncated 815 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033:
 There were test failures: 573 suites (7 ignored), 2319 tests, 2 suite-level 
errors, 1 error, 2 failures, 893 ignored (12 assumptions) [seed: 
FF53D941DD25D8C8]

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



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

Reply via email to