Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/762/

5 tests failed.
REGRESSION:  org.apache.solr.cloud.RollingRestartTest.test

Error Message:
Unable to restart (#6): CloudJettyRunner 
[url=http://127.0.0.1:55998/o_/yp/collection1]

Stack Trace:
java.lang.AssertionError: Unable to restart (#6): CloudJettyRunner 
[url=http://127.0.0.1:55998/o_/yp/collection1]
        at 
__randomizedtesting.SeedInfo.seed([86F6FDD729A0E381:EA2C20D875C8E79]: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:104)
        at 
org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        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:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        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:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)


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

Error Message:
Error from server at http://127.0.0.1:60608/_dp/l: Error CREATEing SolrCore 
'halfcollection_shard1_replica1': Unable to create core 
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core: 
halfcollection_shard1_replica1

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:60608/_dp/l: Error CREATEing SolrCore 
'halfcollection_shard1_replica1': Unable to create core 
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core: 
halfcollection_shard1_replica1
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:302)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:419)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:169)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        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:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        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:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.search.TestReloadDeadlock.testReloadDeadlock

Error Message:
Captured an uncaught exception in thread: Thread[id=5725, name=WRITER2, 
state=RUNNABLE, group=TGRP-TestReloadDeadlock]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=5725, name=WRITER2, state=RUNNABLE, 
group=TGRP-TestReloadDeadlock]
        at 
__randomizedtesting.SeedInfo.seed([86F6FDD729A0E381:BB7759767DEB00C3]:0)
Caused by: java.lang.RuntimeException: org.apache.solr.common.SolrException: 
Error opening new searcher
        at __randomizedtesting.SeedInfo.seed([86F6FDD729A0E381]:0)
        at 
org.apache.solr.search.TestReloadDeadlock$1.run(TestReloadDeadlock.java:166)
Caused by: org.apache.solr.common.SolrException: Error opening new searcher
        at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:1673)
        at org.apache.solr.core.SolrCore.getRealtimeSearcher(SolrCore.java:1530)
        at 
org.apache.solr.update.VersionInfo.getVersionFromIndex(VersionInfo.java:202)
        at org.apache.solr.update.UpdateLog.lookupVersion(UpdateLog.java:783)
        at 
org.apache.solr.update.VersionInfo.lookupVersion(VersionInfo.java:195)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1088)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:705)
        at 
org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:104)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:470)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:134)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:113)
        at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:76)
        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:98)
        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:151)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2079)
        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:131)
        at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1104)
        at 
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1250)
        at 
org.apache.solr.search.TestReloadDeadlock.addDoc(TestReloadDeadlock.java:200)
        at 
org.apache.solr.search.TestReloadDeadlock.access$100(TestReloadDeadlock.java:46)
        at 
org.apache.solr.search.TestReloadDeadlock$1.run(TestReloadDeadlock.java:156)
Caused by: java.lang.NullPointerException
        at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:1642)
        ... 21 more


FAILED:  org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTests

Error Message:
There are still nodes recoverying - waited for 330 seconds

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 330 
seconds
        at 
__randomizedtesting.SeedInfo.seed([86F6FDD729A0E381:8E9688FB26AECB8A]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:172)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:128)
        at 
org.apache.solr.cloud.BaseCdcrDistributedZkTest.waitForRecoveriesToFinish(BaseCdcrDistributedZkTest.java:465)
        at 
org.apache.solr.cloud.BaseCdcrDistributedZkTest.clearSourceCollection(BaseCdcrDistributedZkTest.java:319)
        at 
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTestDeleteCreateSourceCollection(CdcrReplicationDistributedZkTest.java:87)
        at 
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTests(CdcrReplicationDistributedZkTest.java:47)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        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:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        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:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)


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

Error Message:
Captured an uncaught exception in thread: Thread[id=21067, name=collection1, 
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=21067, name=collection1, state=RUNNABLE, 
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:54384: Could not find collection : 
awholynewstresscollection_collection1_0
        at __randomizedtesting.SeedInfo.seed([86F6FDD729A0E381]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895)




Build Log:
[...truncated 10418 lines...]
   [junit4] Suite: org.apache.solr.search.TestReloadDeadlock
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_86F6FDD729A0E381-001/init-core-data-001
   [junit4]   2> 654320 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 654320 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 654320 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 654321 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 654321 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 654321 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 654348 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 654351 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 654359 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] o.a.s.c.Config 
Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 654360 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 654379 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 654548 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 654549 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 654550 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 654552 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 654561 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 654561 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr
   [junit4]   2> 654561 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/'
   [junit4]   2> 654584 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 167262860
   [junit4]   2> 654585 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/]
   [junit4]   2> 654585 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/lib
   [junit4]   2> 654585 WARN  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/lib).
   [junit4]   2> 654601 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 
600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 654605 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 654605 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 654605 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 654606 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 654606 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 654606 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig-tlog.xml, transient=false, schema=schema15.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
instanceDir=collection1, collection=collection1, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/,
 
dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_86F6FDD729A0E381-001/init-core-data-001,
 shard=shard1}
   [junit4]   2> 654607 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 654609 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 654610 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 654610 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 654629 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 654642 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 654658 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 654659 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 654662 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 654798 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 654802 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 654803 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 654805 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 654812 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf'
   [junit4]   2> 654812 INFO  (coreLoadExecutor-2806-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 654813 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 654814 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/],
 dataDir=[null]
   [junit4]   2> 654814 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@323d993
   [junit4]   2> 654817 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_86F6FDD729A0E381-001/init-core-data-001
   [junit4]   2> 654817 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_86F6FDD729A0E381-001/init-core-data-001/index/
   [junit4]   2> 654820 WARN  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_86F6FDD729A0E381-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 654821 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_86F6FDD729A0E381-001/init-core-data-001/index
   [junit4]   2> 654821 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=30, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=32.1064453125, 
floorSegmentMB=1.40234375, forceMergeDeletesPctAllowed=17.75597964391037, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7904232280295753
   [junit4]   2> 654821 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_1,generation=1}
   [junit4]   2> 654821 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 654824 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_86F6FDD729A0E381-001/init-core-data-001/
   [junit4]   2> 654828 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 654828 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 654829 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 654829 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 654829 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 654829 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 654829 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 654829 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 654829 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 654831 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 654832 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 654833 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 654834 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 654836 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 654837 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 654837 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 654837 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 654838 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 654838 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 654839 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=38, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.6435546875, 
noCFSRatio=0.1972335179349196]
   [junit4]   2> 654839 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_1,generation=1}
   [junit4]   2> 654839 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 654839 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2340131[collection1] 
main
   [junit4]   2> 654839 WARN  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 654840 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 654840 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 654840 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 654840 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 654840 INFO  
(searcherExecutor-2807-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2340131[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 654840 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 654841 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 654841 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 654841 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1509234348668223488
   [junit4]   2> 654843 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with 
highest version 1509234348668223488
   [junit4]   2> 654843 INFO  (coreLoadExecutor-2806-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 654844 INFO  
(SUITE-TestReloadDeadlock-seed#[86F6FDD729A0E381]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 654847 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testReloadDeadlock
   [junit4]   2> 654848 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 654848 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@4ff89fd9[collection1] realtime
   [junit4]   2> 654848 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=*:* (-9223372036854775807)} 0 0
   [junit4]   2> 654853 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 654853 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_2,generation=2}
   [junit4]   2> 654854 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 654855 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@13bbcc0a[collection1] main
   [junit4]   2> 654855 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 654856 INFO  (searcherExecutor-2807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13bbcc0a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 654856 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 7
   [junit4]   2> 654863 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(1)]} 0 3
   [junit4]   2> 654863 INFO  (WRITER8) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 654864 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(8)]} 0 3
   [junit4]   2> 654865 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(5)]} 0 4
   [junit4]   2> 654865 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(4)]} 0 4
   [junit4]   2> 654865 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(2)]} 0 5
   [junit4]   2> 654865 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(3)]} 0 5
   [junit4]   2> 654866 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(7)]} 0 5
   [junit4]   2> 654867 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(10)]} 0 2
   [junit4]   2> 654865 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(6)]} 0 4
   [junit4]   2> 654867 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(9)]} 0 3
   [junit4]   2> 654868 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(14)]} 0 1
   [junit4]   2> 654872 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(11)]} 0 6
   [junit4]   2> 654873 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(16)]} 0 6
   [junit4]   2> 654876 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(13)]} 0 9
   [junit4]   2> 654876 INFO  (WRITER8) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_3,generation=3}
   [junit4]   2> 654876 INFO  (WRITER8) [    ] o.a.s.c.SolrCore newest commit 
generation = 3
   [junit4]   2> 654878 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1f69d2d2[collection1] main
   [junit4]   2> 654879 INFO  (WRITER8) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 654879 INFO  (searcherExecutor-2807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f69d2d2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):c1)
 Uninverting(_5(6.0.0):c3/2:delGen=1) Uninverting(_4(6.0.0):c2) 
Uninverting(_6(6.0.0):c4/1:delGen=1)))}
   [junit4]   2> 654889 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 25
   [junit4]   2> 654899 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2461a0da[collection1] realtime
   [junit4]   2> 654899 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-12&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-12)} 0 32
   [junit4]   2> 654900 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(17)]} 0 33
   [junit4]   2> 654900 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(18)]} 0 33
   [junit4]   2> 654903 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@d7731f9[collection1] realtime
   [junit4]   2> 654910 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(21)]} 0 37
   [junit4]   2> 654915 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(15)]} 0 47
   [junit4]   2> 654917 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(20)]} 0 43
   [junit4]   2> 654930 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@40acf62e[collection1] realtime
   [junit4]   2> 654931 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-22&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:10 (-22)} 0 57
   [junit4]   2> 654934 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(24)]} 0 35
   [junit4]   2> 654935 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(25)]} 0 34
   [junit4]   2> 654935 INFO  (WRITER8) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 654937 INFO  (WRITER8) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_4,generation=4}
   [junit4]   2> 654937 INFO  (WRITER8) [    ] o.a.s.c.SolrCore newest commit 
generation = 4
   [junit4]   2> 654937 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-19&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-19)} 0 69
   [junit4]   2> 654938 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@667aba76[collection1] main
   [junit4]   2> 654939 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(23)]} 0 62
   [junit4]   2> 654939 INFO  (searcherExecutor-2807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@667aba76[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2/1:delGen=1)
 Uninverting(_6(6.0.0):c4/2:delGen=1) Uninverting(_8(6.0.0):c2) 
Uninverting(_9(6.0.0):c2) Uninverting(_a(6.0.0):c1)))}
   [junit4]   2> 654939 INFO  (WRITER8) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 654942 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 45
   [junit4]   2> 654948 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@496b2ea7[collection1] realtime
   [junit4]   2> 654950 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-27&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-27)} 0 38
   [junit4]   2> 654950 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(28)]} 0 33
   [junit4]   2> 654950 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-26&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-26)} 0 49
   [junit4]   2> 654950 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(29)]} 0 33
   [junit4]   2> 654951 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(30)]} 0 16
   [junit4]   2> 654953 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4a62cfe3[collection1] realtime
   [junit4]   2> 654953 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-32&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:13 (-32)} 0 17
   [junit4]   2> 654953 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=31,id=12} DBQs=[DBQ{version=32,q=id:13}]
   [junit4]   2> 654954 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(34)]} 0 14
   [junit4]   2> 654955 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4e77d868[collection1] realtime
   [junit4]   2> 654955 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(31)]} 0 20
   [junit4]   2> 654957 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@159b991a[collection1] realtime
   [junit4]   2> 654958 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7ef124d4[collection1] realtime
   [junit4]   2> 654958 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-36&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:3 (-36)} 0 8
   [junit4]   2> 654960 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(38)]} 0 10
   [junit4]   2> 654961 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-35&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-35)} 0 18
   [junit4]   2> 654961 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(33)]} 0 21
   [junit4]   2> 654967 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5610bff2[collection1] realtime
   [junit4]   2> 654967 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-39&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:15 (-39)} 0 16
   [junit4]   2> 654967 INFO  (WRITER1) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=37,id=15} DBQs=[DBQ{version=39,q=id:15}]
   [junit4]   2> 654969 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5d18fd9[collection1] realtime
   [junit4]   2> 654970 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(37)]} 0 18
   [junit4]   2> 654970 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(40)]} 0 19
   [junit4]   2> 654970 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(42)]} 0 16
   [junit4]   2> 654971 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(41)]} 0 18
   [junit4]   2> 654975 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@53e1e5ab[collection1] realtime
   [junit4]   2> 654975 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-43&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-43)} 0 19
   [junit4]   2> 654976 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(44)]} 0 17
   [junit4]   2> 654977 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(46)]} 0 15
   [junit4]   2> 654977 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(47)]} 0 16
   [junit4]   2> 654977 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16 
(45)]} 0 16
   [junit4]   2> 654978 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(48)]} 0 10
   [junit4]   2> 654979 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(49)]} 0 8
   [junit4]   2> 654986 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(51)]} 0 13
   [junit4]   2> 654986 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(50)]} 0 14
   [junit4]   2> 654987 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(52)]} 0 13
   [junit4]   2> 654988 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 654989 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(53)]} 0 13
   [junit4]   2> 654989 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 654989 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(54)]} 0 12
   [junit4]   2> 654990 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16 
(57)]} 0 12
   [junit4]   2> 654990 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(55)]} 0 9
   [junit4]   2> 654991 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(56)]} 0 6
   [junit4]   2> 654991 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(58)]} 0 6
   [junit4]   2> 654993 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(60)]} 0 6
   [junit4]   2> 654993 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(62)]} 0 6
   [junit4]   2> 654996 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@305315b8[collection1] realtime
   [junit4]   2> 654996 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-64&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:13 (-64)} 0 6
   [junit4]   2> 654997 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=63,id=5} DBQs=[DBQ{version=64,q=id:13}]
   [junit4]   2> 654999 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(65)]} 0 8
   [junit4]   2> 654989 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 655002 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 654994 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(61)]} 0 6
   [junit4]   2> 654993 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(59)]} 0 4
   [junit4]   2> 655014 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1bb07865[collection1] realtime
   [junit4]   2> 655015 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7bd418a9[collection1] realtime
   [junit4]   2> 655015 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-66&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-66)} 0 24
   [junit4]   2> 655016 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(67)]} 0 24
   [junit4]   2> 655018 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2ebbb36a[collection1] realtime
   [junit4]   2> 655019 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-68&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-68)} 0 27
   [junit4]   2> 655019 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(69)]} 0 26
   [junit4]   2> 655020 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(70)]} 0 25
   [junit4]   2> 655020 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(72)]} 0 21
   [junit4]   2> 655020 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(71)]} 0 21
   [junit4]   2> 655020 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(74)]} 0 16
   [junit4]   2> 655020 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(63)]} 0 31
   [junit4]   2> 655021 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(75)]} 0 4
   [junit4]   2> 655021 INFO  (WRITER9) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 655022 INFO  (WRITER9) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_4,generation=4}
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_5,generation=5}
   [junit4]   2> 655022 INFO  (WRITER9) [    ] o.a.s.c.SolrCore newest commit 
generation = 5
   [junit4]   2> 655022 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4fcf0de4[collection1] main
   [junit4]   2> 655023 INFO  (WRITER9) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 655023 INFO  (searcherExecutor-2807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4fcf0de4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(6.0.0):c4/3:delGen=2)
 Uninverting(_8(6.0.0):c2/1:delGen=1) Uninverting(_b(6.0.0):c3/1:delGen=1) 
Uninverting(_h(6.0.0):c19/15:delGen=2) Uninverting(_i(6.0.0):c1) 
Uninverting(_j(6.0.0):c1) Uninverting(_l(6.0.0):c6/3:delGen=1)))}
   [junit4]   2> 655024 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 655028 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2b3290c1[collection1] realtime
   [junit4]   2> 655028 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-76&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-76)} 0 9
   [junit4]   2> 655029 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(77)]} 0 9
   [junit4]   2> 655029 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(78)]} 0 9
   [junit4]   2> 655030 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(79)]} 0 9
   [junit4]   2> 655031 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@24e72607[collection1] realtime
   [junit4]   2> 655032 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-80&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-80)} 0 11
   [junit4]   2> 655033 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(73)]} 0 29
   [junit4]   2> 655033 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(81)]} 0 10
   [junit4]   2> 655033 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(83)]} 0 10
   [junit4]   2> 655034 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(82)]} 0 13
   [junit4]   2> 655037 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4d1ab3c6[collection1] realtime
   [junit4]   2> 655037 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-86&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-86)} 0 7
   [junit4]   2> 655038 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=85,id=6} DBQs=[DBQ{version=86,q=id:8}]
   [junit4]   2> 655038 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(87)]} 0 5
   [junit4]   2> 655039 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7429d5e8[collection1] realtime
   [junit4]   2> 655039 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(85)]} 0 7
   [junit4]   2> 655038 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(88)]} 0 5
   [junit4]   2> 655040 INFO  (WRITER9) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=84,id=13} DBQs=[DBQ{version=86,q=id:8}]
   [junit4]   2> 655044 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@20508a8b[collection1] realtime
   [junit4]   2> 655045 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@637920d5[collection1] realtime
   [junit4]   2> 655045 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-89&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:12 (-89)} 0 12
   [junit4]   2> 655047 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(91)]} 0 13
   [junit4]   2> 655049 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@32d8c53a[collection1] realtime
   [junit4]   2> 655049 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-92&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-92)} 0 15
   [junit4]   2> 655049 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(94)]} 0 12
   [junit4]   2> 655050 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(95)]} 0 11
   [junit4]   2> 655050 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(93)]} 0 11
   [junit4]   2> 655050 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(97)]} 0 10
   [junit4]   2> 655051 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(96)]} 0 10
   [junit4]   2> 655051 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(98)]} 0 5
   [junit4]   2> 655051 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(99)]} 0 4
   [junit4]   2> 655052 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(100)]} 0 2
   [junit4]   2> 655052 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(101)]} 0 2
   [junit4]   2> 655052 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 655053 INFO  (WRITER3) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_5,generation=5}
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_6,generation=6}
   [junit4]   2> 655053 INFO  (WRITER3) [    ] o.a.s.c.SolrCore newest commit 
generation = 6
   [junit4]   2> 655054 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-90&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-90)} 0 21
   [junit4]   2> 655054 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(84)]} 0 21
   [junit4]   2> 655055 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@b041b6d[collection1] main
   [junit4]   2> 655058 INFO  (searcherExecutor-2807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@b041b6d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(6.0.0):c2/1:delGen=1)
 Uninverting(_b(6.0.0):c3/2:delGen=2) Uninverting(_h(6.0.0):c19/17:delGen=3) 
Uninverting(_m(6.0.0):c3/2:delGen=2) Uninverting(_n(6.0.0):c1) 
Uninverting(_r(6.0.0):c1) Uninverting(_t(6.0.0):c9/2:delGen=1)))}
   [junit4]   2> 655059 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 655059 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 655062 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@43c3b80c[collection1] realtime
   [junit4]   2> 655062 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-102&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:9 (-102)} 0 11
   [junit4]   2> 655063 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(104)]} 0 11
   [junit4]   2> 655065 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@698e6622[collection1] realtime
   [junit4]   2> 655065 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(106)]} 0 13
   [junit4]   2> 655065 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(107)]} 0 13
   [junit4]   2> 655068 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@209fa220[collection1] realtime
   [junit4]   2> 655068 INFO  (WRITER5) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=103,id=9} DBQs=[DBQ{version=108,q=id:15}, 
DBQ{version=105,q=id:0}]
   [junit4]   2> 655070 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@71bce22b[collection1] realtime
   [junit4]   2> 655071 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(103)]} 0 16
   [junit4]   2> 655071 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-108&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:15 (-108)} 0 18
   [junit4]   2> 655071 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-105&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-105)} 0 20
   [junit4]   2> 655072 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(109)]} 0 17
   [junit4]   2> 655072 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(110)]} 0 17
   [junit4]   2> 655072 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(112)]} 0 10
   [junit4]   2> 655073 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(114)]} 0 7
   [junit4]   2> 655073 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(115)]} 0 7
   [junit4]   2> 655075 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5905c61e[collection1] realtime
   [junit4]   2> 655075 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-113&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-113)} 0 9
   [junit4]   2> 655075 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(111)]} 0 9
   [junit4]   2> 655076 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(116)]} 0 4
   [junit4]   2> 655076 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(118)]} 0 4
   [junit4]   2> 655077 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(119)]} 0 4
   [junit4]   2> 655077 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(120)]} 0 4
   [junit4]   2> 655077 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(121)]} 0 4
   [junit4]   2> 655079 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@cad5c71[collection1] realtime
   [junit4]   2> 655079 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-123&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:17 (-123)} 0 6
   [junit4]   2> 655080 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(124)]} 0 4
   [junit4]   2> 655082 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@bf6c1bd[collection1] realtime
   [junit4]   2> 655083 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-122&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:12 (-122)} 0 7
   [junit4]   2> 655084 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-125&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:12 (-125)} 0 8
   [junit4]   2> 655084 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(127)]} 0 7
   [junit4]   2> 655084 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(128)]} 0 7
   [junit4]   2> 655085 INFO  (WRITER9) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 655086 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-117&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:15 (-117)} 0 10
   [junit4]   2> 655086 INFO  (WRITER9) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_6,generation=6}
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_7,generation=7}
   [junit4]   2> 655086 INFO  (WRITER9) [    ] o.a.s.c.SolrCore newest commit 
generation = 7
   [junit4]   2> 655090 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@17b95b5a[collection1] main
   [junit4]   2> 655092 INFO  (WRITER9) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 655103 INFO  (searcherExecutor-2807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17b95b5a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(6.0.0):c19/17:delGen=3)
 Uninverting(_m(6.0.0):c3/2:delGen=2) Uninverting(_t(6.0.0):c9/5:delGen=2) 
Uninverting(_u(6.0.0):c1) Uninverting(_x(6.0.0):c5/3:delGen=2) 
Uninverting(_y(6.0.0):c5/4:delGen=2) Uninverting(_10(6.0.0):c2)))}
   [junit4]   2> 655103 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 26
   [junit4]   2> 655105 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2c3b53a2[collection1] realtime
   [junit4]   2> 655106 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 655106 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-129&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:15 (-129)} 0 28
   [junit4]   2> 655114 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(130)]} 0 33
   [junit4]   2> 655116 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@92cad6a[collection1] realtime
   [junit4]   2> 655116 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-131&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:17 (-131)} 0 35
   [junit4]   2> 655116 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(132)]} 0 33
   [junit4]   2> 655117 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(133)]} 0 32
   [junit4]   2> 655117 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(134)]} 0 32
   [junit4]   2> 655117 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(135)]} 0 32
   [junit4]   2> 655118 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(136)]} 0 31
   [junit4]   2> 655118 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-126&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:9 (-126)} 0 40
   [junit4]   2> 655118 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 655120 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5470363f[collection1] realtime
   [junit4]   2> 655120 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-137&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:3 (-137)} 0 16
   [junit4]   2> 655121 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-138&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:3 (-138)} 0 6
   [junit4]   2> 655122 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(139)]} 0 7
   [junit4]   2> 655122 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(141)]} 0 4
   [junit4]   2> 655122 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(143)]} 0 5
   [junit4]   2> 655123 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(144)]} 0 4
   [junit4]   2> 655123 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(142)]} 0 4
   [junit4]   2> 655123 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(145)]} 0 4
   [junit4]   2> 655123 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(140)]} 0 5
   [junit4]   2> 655132 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7d5b8913[collection1] realtime
   [junit4]   2> 655132 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-146&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-146)} 0 11
   [junit4]   2> 655132 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 655133 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(147)]} 0 12
   [junit4]   2> 655134 INFO  (WRITER0) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_7,generation=7}
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3129ae3c 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64cfe7f7),segFN=segments_8,generation=8}
   [junit4]   2> 655134 INFO  (WRITER0) [    ] o.a.s.c.SolrCore newest commit 
generation = 8
   [junit4]   2> 655134 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1f29300e[collection1] main
   [junit4]   2> 655135 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 655135 INFO  (searcherExecutor-2807-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f29300e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(6.0.0):c19/17:delGen=3)
 Uninverting(_m(6.0.0):c3/2:delGen=2) Uninverting(_t(6.0.0):c9/6:delGen=3) 
Uninverting(_u(6.0.0):c1) Uninverting(_x(6.0.0):c5/4:delGen=3) 
Uninverting(_14(6.0.0):c4/2:delGen=2) Uninverting(_13(6.0.0):c1) 
Uninverting(_15(6.0.0):c1) Uninverting(_16(6.0.0):c1)))}
   [junit4]   2> 655136 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 14
   [junit4]   2> 655140 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 655141 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[86F6FDD729A0E381]) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 655141 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@12f26104[collection1] realtime
   [junit4]   2> 655141 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-148&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:15 (-148)} 0 19
   [junit4]   2> 655145 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(153)]} 0 21
   [junit4]   2> 655145 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(149)]} 0 21
   [junit4]   2> 655145 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(154)]} 0 21
   [junit4]   2> 655146 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(155)]} 0 12
   [junit4]   2> 655146 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(156)]} 0 12
   [junit4]   2> 655146 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(151)]} 0 23
   [junit4]   2> 655147 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=

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

57757_o_%2Fyp-n_0000000107
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573554-127.0.0.1:49456_o_%2Fyp-n_0000000050
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573572-127.0.0.1:46261_o_%2Fyp-n_0000000056
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard2/election/94327302400573749-core_node5-n_0000000053
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard2/election/94327302400573557-core_node5-n_0000000017
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573491-127.0.0.1:35860_o_%2Fyp-n_0000000015
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573557-127.0.0.1:55998_o_%2Fyp-n_0000000051
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573509-127.0.0.1:55998_o_%2Fyp-n_0000000029
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573518-127.0.0.1:38242_o_%2Fyp-n_0000000032
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573818-127.0.0.1:51580_o_%2Fyp-n_0000000174
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573467-127.0.0.1:51003_o_%2Fyp-n_0000000024
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573767-127.0.0.1:57757_o_%2Fyp-n_0000000151
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard2/election/94327302400573473-core_node9-n_0000000004
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573719-127.0.0.1:57757_o_%2Fyp-n_0000000129
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573566-127.0.0.1:38242_o_%2Fyp-n_0000000054
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573620-127.0.0.1:46261_o_%2Fyp-n_0000000084
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard1/election/94327302400573716-core_node6-n_0000000041
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573701-127.0.0.1:55998_o_%2Fyp-n_0000000117
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573716-127.0.0.1:46261_o_%2Fyp-n_0000000128
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573764-127.0.0.1:46261_o_%2Fyp-n_0000000150
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573710-127.0.0.1:38242_o_%2Fyp-n_0000000126
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573476-127.0.0.1:36410_o_%2Fyp-n_0000000010
   [junit4]   2>        2       
/solr/overseer_elect/election/94327302400573650-127.0.0.1:49456_o_%2Fyp-n_0000000094
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard2/election/94327302400573797-core_node5-n_0000000062
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard1/election/94327302400573464-core_node6-n_0000000002
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard2/election/94327302400573701-core_node5-n_0000000044
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        55      /solr/overseer/collection-queue-work
   [junit4]   2>        52      /solr/overseer/queue
   [junit4]   2>        18      /solr/live_nodes
   [junit4]   2> 
   [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=RollingRestartTest 
-Dtests.method=test -Dtests.seed=86F6FDD729A0E381 -Dtests.multiplier=2 
-Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=in -Dtests.timezone=Europe/Tirane -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE  849s J0 | RollingRestartTest.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Unable to restart 
(#6): CloudJettyRunner [url=http://127.0.0.1:55998/o_/yp/collection1]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([86F6FDD729A0E381:EA2C20D875C8E79]:0)
   [junit4]    >        at 
org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:104)
   [junit4]    >        at 
org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 3878045 INFO  
(SUITE-RollingRestartTest-seed#[86F6FDD729A0E381]-worker) 
[n:127.0.0.1:39093_o_%2Fyp c:collection1 s:shard1 r:core_node16 x:collection1] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.RollingRestartTest_86F6FDD729A0E381-001
   [junit4]   2> Aug 11, 2015 3:27:38 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=in, 
timezone=Europe/Tirane
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_45 (64-bit)/cpus=4,threads=1,free=168322592,total=524812288
   [junit4]   2> NOTE: All tests run in this JVM: [CdcrUpdateLogTest, 
TestNamedUpdateProcessors, TestConfigOverlay, TestCSVLoader, TestCodecSupport, 
OverseerStatusTest, TestRandomDVFaceting, DeleteInactiveReplicaTest, 
TestWordDelimiterFilterFactory, QueryElevationComponentTest, 
UpdateRequestProcessorFactoryTest, AnalyticsMergeStrategyTest, 
DistributedFacetPivotLargeTest, FullHLLTest, TestHashQParserPlugin, 
DateFieldTest, TestLazyCores, TestFaceting, TestOrdValues, MinimalSchemaTest, 
CSVRequestHandlerTest, TestManagedStopFilterFactory, TestArbitraryIndexDir, 
TestRemoteStreaming, ExternalFileFieldSortTest, TestRangeQuery, TestCustomSort, 
HdfsSyncSliceTest, BasicAuthIntegrationTest, SimpleMLTQParserTest, 
TestSolr4Spatial, TestSolrConfigHandlerCloud, TestAuthorizationFramework, 
SuggesterWFSTTest, TestSha256AuthenticationProvider, TestSmileRequest, 
TestCloudManagedSchemaConcurrent, TestDynamicFieldCollectionResource, 
DocValuesMissingTest, SolrRequestParserTest, DebugComponentTest, 
TestFunctionQuery, TestShardHandlerFactory, CoreMergeIndexesAdminHandlerTest, 
IndexBasedSpellCheckerTest, SharedFSAutoReplicaFailoverTest, BJQParserTest, 
URLClassifyProcessorTest, SolrIndexConfigTest, BlockCacheTest, BasicZkTest, 
UniqFieldsUpdateProcessorFactoryTest, ZkNodePropsTest, RequestHandlersTest, 
SignatureUpdateProcessorFactoryTest, PingRequestHandlerTest, UUIDFieldTest, 
DefaultValueUpdateProcessorTest, TestConfig, SolrCoreTest, 
TestSimpleTrackingShardHandler, TestIBSimilarityFactory, 
SchemaVersionSpecificBehaviorTest, TestScoreJoinQPScore, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestReplicaProperties, 
SparseHLLTest, HdfsDirectoryTest, TestMiniSolrCloudCluster, 
TestManagedResource, TestClassNameShortening, HighlighterTest, 
TestHdfsUpdateLog, HighlighterConfigTest, TestConfigSetProperties, 
TestCollationFieldDocValues, TestMinMaxOnMultiValuedField, TestBinaryField, 
TestPostingsSolrHighlighter, ChaosMonkeyNothingIsSafeTest, 
TestFileDictionaryLookup, TestTrackingShardHandlerFactory, 
TestSweetSpotSimilarityFactory, TestQuerySenderListener, TestInfoStreamLogging, 
TestManagedResourceStorage, SyncSliceTest, OpenCloseCoreStressTest, 
TestRandomFaceting, LeaderElectionTest, TestZkChroot, TestDistributedSearch, 
AutoCommitTest, TestDistributedGrouping, ZkControllerTest, TestRealTimeGet, 
TestStressReorder, TestReload, SpellCheckComponentTest, PeerSyncTest, TestSort, 
BasicFunctionalityTest, DirectUpdateHandlerTest, 
OverseerCollectionProcessorTest, ShowFileRequestHandlerTest, 
CurrencyFieldXmlFileTest, TestExtendedDismaxParser, SuggesterTSTTest, 
TestStressLucene, TestUpdate, QueryEqualityTest, DistanceFunctionTest, 
SolrInfoMBeanTest, TestSurroundQueryParser, TestWriterPerf, TestQueryTypes, 
FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, TestValueSourceCache, 
TermVectorComponentTest, MoreLikeThisHandlerTest, TestSolrQueryParser, 
IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, TestJmxIntegration, 
ReturnFieldsTest, QueryParsingTest, JsonLoaderTest, 
TestPartialUpdateDeduplication, SearchHandlerTest, TestLFUCache, 
CdcrRequestHandlerTest, CdcrVersionReplicationTest, CollectionReloadTest, 
CollectionsAPIAsyncDistributedZkTest, DeleteShardTest, HttpPartitionTest, 
MigrateRouteKeyTest, RecoveryAfterSoftCommitTest, RollingRestartTest]
   [junit4] Completed [456/540] on J0 in 848.96s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 261 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:723:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:660:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:514:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1429:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:984:
 There were test failures: 540 suites, 2128 tests, 3 errors, 2 failures, 26 
ignored (14 assumptions)

Total time: 175 minutes 33 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
Sending email for trigger: Failure


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

Reply via email to