Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/831/

2 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest

Error Message:
ERROR: SolrIndexSearcher opens=31 closes=30

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=31 closes=30
        at __randomizedtesting.SeedInfo.seed([2C9192D375EDBFDD]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:496)
        at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:232)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
        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:  
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest

Error Message:
2 threads leaked from SUITE scope at 
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest:     1) Thread[id=2651, 
name=qtp322780548-2651, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] 
        at java.lang.Object.wait(Native Method)         at 
java.lang.Object.wait(Object.java:503)         at 
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397)
         at 
org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
         at 
org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229)
         at 
org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)    
     at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782)
         at 
org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649)
         at 
org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
         at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
         at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
         at org.apache.solr.core.SolrCore.execute(SolrCore.java:2047)         
at 
org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) 
        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
         at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
         at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
         at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
         at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
         at 
org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)    
     at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)     
    at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
         at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)      
   at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
         at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
         at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)       
  at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
         at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
         at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)   
      at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)  
       at org.eclipse.jetty.server.Server.handle(Server.java:497)         at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)         at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)     
    at 
org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)      
   at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) 
        at java.lang.Thread.run(Thread.java:745)    2) Thread[id=2661, 
name=searcherExecutor-1417-thread-1, state=WAITING, 
group=TGRP-ChaosMonkeyNothingIsSafeTest]         at sun.misc.Unsafe.park(Native 
Method)         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)     
    at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)   
      at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) 
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) 
        at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE 
scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest: 
   1) Thread[id=2651, name=qtp322780548-2651, state=WAITING, 
group=TGRP-ChaosMonkeyNothingIsSafeTest]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:503)
        at 
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397)
        at 
org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
        at 
org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229)
        at 
org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649)
        at 
org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2047)
        at 
org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
        at 
org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
        at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)
        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
        at org.eclipse.jetty.server.Server.handle(Server.java:497)
        at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
        at 
org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=2661, name=searcherExecutor-1417-thread-1, state=WAITING, 
group=TGRP-ChaosMonkeyNothingIsSafeTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
        at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
        at __randomizedtesting.SeedInfo.seed([2C9192D375EDBFDD]:0)




Build Log:
[...truncated 11225 lines...]
   [junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
   [junit4]   2> Creating dataDir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/init-core-data-001
   [junit4]   2> 1627405 T2578 
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system 
property: /yc_wo/l
   [junit4]   2> 1627411 T2578 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 1627411 T2579 oasc.ZkTestServer$2$1.setClientPort client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1627411 T2579 oasc.ZkTestServer$ZKServerMain.runFromConfig 
Starting server
   [junit4]   2> 1627511 T2578 oasc.ZkTestServer.run start zk server on 
port:31761
   [junit4]   2> 1627512 T2578 
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default 
ZkCredentialsProvider
   [junit4]   2> 1627513 T2578 oascc.ConnectionManager.waitForConnected Waiting 
for client to connect to ZooKeeper
   [junit4]   2> 1627516 T2586 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@1ac86051 
name:ZooKeeperConnection Watcher:127.0.0.1:31761 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1627516 T2578 oascc.ConnectionManager.waitForConnected Client 
is connected to ZooKeeper
   [junit4]   2> 1627516 T2578 oascc.SolrZkClient.createZkACLProvider Using 
default ZkACLProvider
   [junit4]   2> 1627517 T2578 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 1627519 T2578 
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default 
ZkCredentialsProvider
   [junit4]   2> 1627519 T2578 oascc.ConnectionManager.waitForConnected Waiting 
for client to connect to ZooKeeper
   [junit4]   2> 1627520 T2589 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@77b3be7b 
name:ZooKeeperConnection Watcher:127.0.0.1:31761/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1627521 T2578 oascc.ConnectionManager.waitForConnected Client 
is connected to ZooKeeper
   [junit4]   2> 1627521 T2578 oascc.SolrZkClient.createZkACLProvider Using 
default ZkACLProvider
   [junit4]   2> 1627521 T2578 oascc.SolrZkClient.makePath makePath: 
/collections/collection1
   [junit4]   2> 1627523 T2578 oascc.SolrZkClient.makePath makePath: 
/collections/collection1/shards
   [junit4]   2> 1627524 T2578 oascc.SolrZkClient.makePath makePath: 
/collections/control_collection
   [junit4]   2> 1627525 T2578 oascc.SolrZkClient.makePath makePath: 
/collections/control_collection/shards
   [junit4]   2> 1627527 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 1627527 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/solrconfig.xml
   [junit4]   2> 1627529 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema15.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 1627530 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/schema.xml
   [junit4]   2> 1627531 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1627532 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1627533 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 1627534 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/stopwords.txt
   [junit4]   2> 1627535 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 1627535 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/protwords.txt
   [junit4]   2> 1627537 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 1627537 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/currency.xml
   [junit4]   2> 1627539 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1627539 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/enumsConfig.xml
   [junit4]   2> 1627541 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1627541 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/open-exchange-rates.json
   [junit4]   2> 1627542 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1627543 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1627544 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1627545 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/old_synonyms.txt
   [junit4]   2> 1627546 T2578 oasc.AbstractZkTestCase.putConfig put 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 1627547 T2578 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/synonyms.txt
   [junit4]   2> 1628650 T2578 oas.SolrTestCaseJ4.writeCoreProperties Writing 
core.properties file to 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1
   [junit4]   2> 1628653 T2578 oejs.Server.doStart jetty-9.2.10.v20150310
   [junit4]   2> 1628654 T2578 oejsh.ContextHandler.doStart Started 
o.e.j.s.ServletContextHandler@74eabf45{/yc_wo/l,null,AVAILABLE}
   [junit4]   2> 1628657 T2578 oejs.AbstractConnector.doStart Started 
ServerConnector@362b82c{HTTP/1.1}{127.0.0.1:31764}
   [junit4]   2> 1628657 T2578 oejs.Server.doStart Started @1634088ms
   [junit4]   2> 1628658 T2578 oascse.JettySolrRunner$1.lifeCycleStarted Jetty 
properties: {hostPort=31764, 
coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores, 
solr.data.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/tempDir-001/control/data, hostContext=/yc_wo/l}
   [junit4]   2> 1628658 T2578 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@3972aa3f
   [junit4]   2> 1628659 T2578 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/'
   [junit4]   2> 1628911 T2578 oasc.SolrXmlConfig.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/solr.xml
   [junit4]   2> 1628966 T2578 oasc.CorePropertiesLocator.<init> Config-defined 
core root directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores
   [junit4]   2> 1628967 T2578 oasc.CoreContainer.<init> New CoreContainer 
656610949
   [junit4]   2> 1628967 T2578 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/]
   [junit4]   2> 1628967 T2578 oasc.CoreContainer.load loading shared library: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/lib
   [junit4]   2> 1628969 T2578 oasc.SolrResourceLoader.addToClassLoader WARN 
Can't find (or read) directory to add to classloader: lib (resolved as: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/lib).
   [junit4]   2> 1628982 T2578 oashc.HttpShardHandlerFactory.init created with 
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : 
false,useRetries : false,
   [junit4]   2> 1628989 T2578 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1628990 T2578 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1628990 T2578 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1628990 T2578 oasc.CoreContainer.load Node Name: 127.0.0.1
   [junit4]   2> 1628991 T2578 oasc.ZkContainer.initZooKeeper Zookeeper 
client=127.0.0.1:31761/solr
   [junit4]   2> 1628991 T2578 oasc.ZkController.checkChrootPath zkHost 
includes chroot
   [junit4]   2> 1628991 T2578 
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default 
ZkCredentialsProvider
   [junit4]   2> 1628992 T2578 oascc.ConnectionManager.waitForConnected Waiting 
for client to connect to ZooKeeper
   [junit4]   2> 1628995 T2603 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@4b2858c8 
name:ZooKeeperConnection Watcher:127.0.0.1:31761 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1628996 T2578 oascc.ConnectionManager.waitForConnected Client 
is connected to ZooKeeper
   [junit4]   2> 1628996 T2578 oascc.SolrZkClient.createZkACLProvider Using 
default ZkACLProvider
   [junit4]   2> 1628999 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ConnectionManager.waitForConnected Waiting for client to connect to 
ZooKeeper
   [junit4]   2> 1629000 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@21accaeb 
name:ZooKeeperConnection Watcher:127.0.0.1:31761/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1629000 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1629002 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 1629004 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 1629007 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
   [junit4]   2> 1629009 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
   [junit4]   2> 1629011 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
   [junit4]   2> 1629013 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 1629015 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 1629017 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 1629018 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.ZkController.createEphemeralLiveNode Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:31764_yc_wo%2Fl
   [junit4]   2> 1629019 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:31764_yc_wo%2Fl
   [junit4]   2> 1629021 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 1629022 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 1629024 T2578 n:127.0.0.1:31764_yc_wo%2Fl oasc.Overseer.close 
Overseer (id=null) closing
   [junit4]   2> 1629025 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 
127.0.0.1:31764_yc_wo%2Fl
   [junit4]   2> 1629026 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 1629027 T2578 n:127.0.0.1:31764_yc_wo%2Fl oasc.Overseer.start 
Overseer (id=93731989647392771-127.0.0.1:31764_yc_wo%2Fl-n_0000000000) starting
   [junit4]   2> 1629029 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 1629036 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.OverseerAutoReplicaFailoverThread.<init> Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=30000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1629037 T2608 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.OverseerCollectionProcessor.run Process current queue of collection 
creations
   [junit4]   2> 1629037 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 1629037 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 1629041 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.CorePropertiesLocator.discover Looking for core definitions underneath 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores
   [junit4]   2> 1629045 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.CoreDescriptor.<init> CORE DESCRIPTOR: 
{absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1/, dataDir=data/, 
coreNodeName=, shard=, config=solrconfig.xml, schema=schema.xml, 
transient=false, 
instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1, name=collection1, 
collection=control_collection, loadOnStartup=true}
   [junit4]   2> 1629045 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1/
   [junit4]   2> 1629046 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 1629047 T2610 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
x:collection1 oasc.ZkController.publish publishing core=collection1 state=down 
collection=control_collection
   [junit4]   2> 1629047 T2610 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
x:collection1 oasc.ZkController.publish numShards not found on descriptor - 
reading it from system property
   [junit4]   2> 1629048 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1629048 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1629049 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31764/yc_wo/l";,
   [junit4]   2>          "node_name":"127.0.0.1:31764_yc_wo%2Fl",
   [junit4]   2>          "shard":null,
   [junit4]   2>          "collection":"control_collection",
   [junit4]   2>          "numShards":"1",
   [junit4]   2>          "state":"down",
   [junit4]   2>          "operation":"state",
   [junit4]   2>          "roles":null} current state version: 0
   [junit4]   2> 1629050 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ReplicaMutator.updateState Update state numShards=1 message={
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31764/yc_wo/l";,
   [junit4]   2>          "node_name":"127.0.0.1:31764_yc_wo%2Fl",
   [junit4]   2>          "shard":null,
   [junit4]   2>          "collection":"control_collection",
   [junit4]   2>          "numShards":"1",
   [junit4]   2>          "state":"down",
   [junit4]   2>          "operation":"state",
   [junit4]   2>          "roles":null}
   [junit4]   2> 1629050 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ClusterStateMutator.createCollection building a new cName: 
control_collection
   [junit4]   2> 1629050 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 1629052 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 1630048 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for 
collection1
   [junit4]   2> 1630049 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.ZkController.createCollectionZkNode Check for collection 
zkNode:control_collection
   [junit4]   2> 1630049 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1630049 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ZkStateReader.readConfigName Load collection config 
from:/collections/control_collection
   [junit4]   2> 1630050 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ZkStateReader.readConfigName path=/collections/control_collection 
configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1630050 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1/'
   [junit4]   2> 1630064 T2610 n:127.0.0.1:31764_yc_wo%2Fl oasc.Config.<init> 
loaded config solrconfig.xml with version 0 
   [junit4]   2> 1630071 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
   [junit4]   2> 1630087 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
   [junit4]   2> 1630122 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1630123 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 1630130 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1630364 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1630366 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1630367 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oass.FileExchangeRateProvider.reload Reloading exchange rates from file 
currency.xml
   [junit4]   2> 1630371 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oass.FileExchangeRateProvider.reload Reloading exchange rates from file 
currency.xml
   [junit4]   2> 1630385 T2610 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration 
from collection control_collection
   [junit4]   2> 1630385 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 1630386 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at 
[/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 1630386 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to 
JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@41199ec2
   [junit4]   2> 1630388 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.CachingDirectoryFactory.get return new directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1/data
   [junit4]   2> 1630389 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrCore.getNewIndexDir New index directory detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1630389 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrCore.initIndex WARN [collection1] Solr index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1/data/index' doesn't exist. 
Creating new index...
   [junit4]   2> 1630390 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.CachingDirectoryFactory.get return new directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/control-001/cores/collection1/data/index
   [junit4]   2> 1630390 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@12d707f5
   [junit4]   2> 1630394 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 
2C9192D375EDBFDD-001/control-001/cores/collection1/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 1630394 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1630399 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 1630399 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 1630399 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1630399 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 1630400 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1630400 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"distrib-dup-test-chain-explicit"
   [junit4]   2> 1630400 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1630400 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1630400 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined 
as default, creating implicit default
   [junit4]   2> 1630413 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1630415 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1630417 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1630418 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1630422 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.RequestHandlers.initHandlersFromConfig Registered paths: 
/admin/plugins,standard,/update/json,/admin/file,/admin/system,/update/csv,/schema,/replication,/admin/ping,/update,/config,/admin/segments,/get,/admin/luke,/admin/mbeans,/admin/properties,/admin/threads,/admin/logging,/update/json/docs
   [junit4]   2> 1630423 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrCore.initStatsCache Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1630424 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasu.UpdateHandler.<init> Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1630424 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10
   [junit4]   2> 1630425 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1630425 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1630426 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1702138285, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1630427 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 
2C9192D375EDBFDD-001/control-001/cores/collection1/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 1630427 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1630427 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oass.SolrIndexSearcher.<init> Opening Searcher@12fbdb3b[collection1] main
   [junit4]   2> 1630428 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oascc.ZkStateReader.readConfigName Load collection config 
from:/collections/control_collection
   [junit4]   2> 1630428 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oascc.ZkStateReader.readConfigName path=/collections/control_collection 
configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1630429 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for 
the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1630429 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1630429 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 1630429 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage.load Reading _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1630430 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found 
for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1630430 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1630430 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 1630430 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 1630431 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
   [junit4]   2> 1630431 T2611 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.SolrCore.registerSearcher [collection1] Registered new searcher 
Searcher@12fbdb3b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1630431 T2610 n:127.0.0.1:31764_yc_wo%2Fl x:collection1 
oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1630432 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ZkController.register Register replica - 
core:collection1 address:http://127.0.0.1:31764/yc_wo/l 
collection:control_collection shard:shard1
   [junit4]   2> 1630432 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2
   [junit4]   2> 1630432 T2578 n:127.0.0.1:31764_yc_wo%2Fl 
oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1630433 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: 
/collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 1630434 T2578 
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default 
ZkCredentialsProvider
   [junit4]   2> 1630435 T2578 oascc.ConnectionManager.waitForConnected Waiting 
for client to connect to ZooKeeper
   [junit4]   2> 1630436 T2617 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@269a57 name:ZooKeeperConnection 
Watcher:127.0.0.1:31761/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 1630436 T2578 oascc.ConnectionManager.waitForConnected Client 
is connected to ZooKeeper
   [junit4]   2> 1630437 T2578 oascc.SolrZkClient.createZkACLProvider Using 
default ZkACLProvider
   [junit4]   2> 1630437 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running 
the leader process for shard shard1
   [junit4]   2> 1630437 T2578 
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 1630438 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1630439 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp 
Enough replicas found to continue.
   [junit4]   2> 1630439 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may 
be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C866 name=collection1 
org.apache.solr.core.SolrCore@551d5408 
url=http://127.0.0.1:31764/yc_wo/l/collection1 node=127.0.0.1:31764_yc_wo%2Fl 
C866_STATE=coll:control_collection core:collection1 props:{core=collection1, 
base_url=http://127.0.0.1:31764/yc_wo/l, node_name=127.0.0.1:31764_yc_wo%2Fl, 
state=down}
   [junit4]   2> 1630439 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 C866 oasc.SyncStrategy.sync Sync replicas to 
http://127.0.0.1:31764/yc_wo/l/collection1/
   [junit4]   2> 1630439 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2>          "operation":"leader",
   [junit4]   2>          "shard":"shard1",
   [junit4]   2>          "collection":"control_collection"} current state 
version: 1
   [junit4]   2> 1630439 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 C866 oasc.SyncStrategy.syncReplicas Sync Success - now 
sync replicas to me
   [junit4]   2> 1630440 T2578 oasc.ChaosMonkey.monkeyLog monkey: init - expire 
sessions:false cause connection loss:false
   [junit4]   2> 1630440 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 C866 oasc.SyncStrategy.syncToMe 
http://127.0.0.1:31764/yc_wo/l/collection1/ has no replicas
   [junit4]   2> 1630440 T2578 oasc.AbstractFullDistribZkTestBase.createJettys 
Creating collection1 with stateFormat=2
   [junit4]   2> 1630440 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am 
the new leader: http://127.0.0.1:31764/yc_wo/l/collection1/ shard1
   [junit4]   2> 1630440 T2578 
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default 
ZkCredentialsProvider
   [junit4]   2> 1630440 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: 
/collections/control_collection/leaders/shard1
   [junit4]   2> 1630441 T2578 oascc.ConnectionManager.waitForConnected Waiting 
for client to connect to ZooKeeper
   [junit4]   2> 1630442 T2620 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@19ffb5b3 
name:ZooKeeperConnection Watcher:127.0.0.1:31761/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1630442 T2578 oascc.ConnectionManager.waitForConnected Client 
is connected to ZooKeeper
   [junit4]   2> 1630443 T2578 oascc.SolrZkClient.createZkACLProvider Using 
default ZkACLProvider
   [junit4]   2> 1630444 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1630445 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = {
   [junit4]   2>          "operation":"create",
   [junit4]   2>          "name":"collection1",
   [junit4]   2>          "numShards":"2",
   [junit4]   2>          "stateFormat":"2"} current state version: 1
   [junit4]   2> 1630445 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ClusterStateMutator.createCollection building a new cName: collection1
   [junit4]   2> 1630446 T2617 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 1630446 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 1630449 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2>          "operation":"leader",
   [junit4]   2>          "shard":"shard1",
   [junit4]   2>          "collection":"control_collection",
   [junit4]   2>          "base_url":"http://127.0.0.1:31764/yc_wo/l";,
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "state":"active"} current state version: 2
   [junit4]   2> 1630449 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ZkStateWriter.writePendingUpdates going to create_collection 
/collections/collection1/state.json
   [junit4]   2> 1630450 T2617 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 1630450 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 1630452 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1630453 T2617 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 1630453 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 1630495 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ZkController.register We are 
http://127.0.0.1:31764/yc_wo/l/collection1/ and leader is 
http://127.0.0.1:31764/yc_wo/l/collection1/
   [junit4]   2> 1630496 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for 
core=collection1 baseURL=http://127.0.0.1:31764/yc_wo/l
   [junit4]   2> 1630496 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no 
recovery necessary
   [junit4]   2> 1630496 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1 
state=active collection=control_collection
   [junit4]   2> 1630496 T2614 n:127.0.0.1:31764_yc_wo%2Fl c:control_collection 
s:shard1 x:collection1 oasc.ZkController.publish numShards not found on 
descriptor - reading it from system property
   [junit4]   2> 1630497 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1630498 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "core_node_name":"core_node1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31764/yc_wo/l";,
   [junit4]   2>          "node_name":"127.0.0.1:31764_yc_wo%2Fl",
   [junit4]   2>          "shard":"shard1",
   [junit4]   2>          "collection":"control_collection",
   [junit4]   2>          "numShards":"2",
   [junit4]   2>          "state":"active",
   [junit4]   2>          "operation":"state",
   [junit4]   2>          "roles":null} current state version: 4
   [junit4]   2> 1630499 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ReplicaMutator.updateState Update state numShards=2 message={
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "core_node_name":"core_node1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31764/yc_wo/l";,
   [junit4]   2>          "node_name":"127.0.0.1:31764_yc_wo%2Fl",
   [junit4]   2>          "shard":"shard1",
   [junit4]   2>          "collection":"control_collection",
   [junit4]   2>          "numShards":"2",
   [junit4]   2>          "state":"active",
   [junit4]   2>          "operation":"state",
   [junit4]   2>          "roles":null}
   [junit4]   2> 1630601 T2617 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 1630601 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 1631478 T2578 oas.SolrTestCaseJ4.writeCoreProperties Writing 
core.properties file to 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1
   [junit4]   2> 1631480 T2578 oasc.AbstractFullDistribZkTestBase.createJettys 
create jetty 1 in directory 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001
   [junit4]   2> 1631481 T2578 oejs.Server.doStart jetty-9.2.10.v20150310
   [junit4]   2> 1631483 T2578 oejsh.ContextHandler.doStart Started 
o.e.j.s.ServletContextHandler@332c4508{/yc_wo/l,null,AVAILABLE}
   [junit4]   2> 1631484 T2578 oejs.AbstractConnector.doStart Started 
ServerConnector@1e4a85f3{HTTP/1.1}{127.0.0.1:31771}
   [junit4]   2> 1631484 T2578 oejs.Server.doStart Started @1636915ms
   [junit4]   2> 1631484 T2578 oascse.JettySolrRunner$1.lifeCycleStarted Jetty 
properties: {hostContext=/yc_wo/l, hostPort=31771, solrconfig=solrconfig.xml, 
solr.data.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/tempDir-001/jetty1, 
coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores}
   [junit4]   2> 1631485 T2578 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@3972aa3f
   [junit4]   2> 1631485 T2578 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/'
   [junit4]   2> 1631515 T2578 oasc.SolrXmlConfig.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/solr.xml
   [junit4]   2> 1631556 T2578 oasc.CorePropertiesLocator.<init> Config-defined 
core root directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores
   [junit4]   2> 1631556 T2578 oasc.CoreContainer.<init> New CoreContainer 
409837793
   [junit4]   2> 1631556 T2578 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/]
   [junit4]   2> 1631557 T2578 oasc.CoreContainer.load loading shared library: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/lib
   [junit4]   2> 1631557 T2578 oasc.SolrResourceLoader.addToClassLoader WARN 
Can't find (or read) directory to add to classloader: lib (resolved as: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/lib).
   [junit4]   2> 1631567 T2578 oashc.HttpShardHandlerFactory.init created with 
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : 
false,useRetries : false,
   [junit4]   2> 1631571 T2578 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1631572 T2578 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1631572 T2578 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1631573 T2578 oasc.CoreContainer.load Node Name: 127.0.0.1
   [junit4]   2> 1631573 T2578 oasc.ZkContainer.initZooKeeper Zookeeper 
client=127.0.0.1:31761/solr
   [junit4]   2> 1631573 T2578 oasc.ZkController.checkChrootPath zkHost 
includes chroot
   [junit4]   2> 1631573 T2578 
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default 
ZkCredentialsProvider
   [junit4]   2> 1631574 T2578 oascc.ConnectionManager.waitForConnected Waiting 
for client to connect to ZooKeeper
   [junit4]   2> 1631576 T2634 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@1b95eff5 
name:ZooKeeperConnection Watcher:127.0.0.1:31761 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1631577 T2578 oascc.ConnectionManager.waitForConnected Client 
is connected to ZooKeeper
   [junit4]   2> 1631578 T2578 oascc.SolrZkClient.createZkACLProvider Using 
default ZkACLProvider
   [junit4]   2> 1631580 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ConnectionManager.waitForConnected Waiting for client to connect to 
ZooKeeper
   [junit4]   2> 1631581 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@1c44817b 
name:ZooKeeperConnection Watcher:127.0.0.1:31761/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1631582 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1631587 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 1632593 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.ZkController.createEphemeralLiveNode Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:31771_yc_wo%2Fl
   [junit4]   2> 1632594 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:31771_yc_wo%2Fl
   [junit4]   2> 1632597 T2578 n:127.0.0.1:31771_yc_wo%2Fl oasc.Overseer.close 
Overseer (id=null) closing
   [junit4]   2> 1632599 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.CorePropertiesLocator.discover Looking for core definitions underneath 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores
   [junit4]   2> 1632601 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {schema=schema.xml, dataDir=data/, 
absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1/, loadOnStartup=true, 
instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1, collection=collection1, 
name=collection1, shard=, coreNodeName=, transient=false, config=solrconfig.xml}
   [junit4]   2> 1632601 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1/
   [junit4]   2> 1632601 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 1632603 T2638 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
x:collection1 oasc.ZkController.publish publishing core=collection1 state=down 
collection=collection1
   [junit4]   2> 1632604 T2638 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
x:collection1 oasc.ZkController.publish numShards not found on descriptor - 
reading it from system property
   [junit4]   2> 1632605 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1632606 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.ZkController.preRegister Registering watch for external collection 
collection1
   [junit4]   2> 1632606 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31771/yc_wo/l";,
   [junit4]   2>          "node_name":"127.0.0.1:31771_yc_wo%2Fl",
   [junit4]   2>          "shard":null,
   [junit4]   2>          "collection":"collection1",
   [junit4]   2>          "numShards":"2",
   [junit4]   2>          "state":"down",
   [junit4]   2>          "operation":"state",
   [junit4]   2>          "roles":null} current state version: 5
   [junit4]   2> 1632606 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.addZkWatch addZkWatch collection1
   [junit4]   2> 1632606 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ReplicaMutator.updateState Update state numShards=2 message={
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31771/yc_wo/l";,
   [junit4]   2>          "node_name":"127.0.0.1:31771_yc_wo%2Fl",
   [junit4]   2>          "shard":null,
   [junit4]   2>          "collection":"collection1",
   [junit4]   2>          "numShards":"2",
   [junit4]   2>          "state":"down",
   [junit4]   2>          "operation":"state",
   [junit4]   2>          "roles":null}
   [junit4]   2> 1632607 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.addZkWatch Updating collection state at 
/collections/collection1/state.json from ZooKeeper... 
   [junit4]   2> 1632607 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ReplicaMutator.updateState Collection already exists with numShards=2
   [junit4]   2> 1632607 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 1632608 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to 
ver 0 
   [junit4]   2> 1632608 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1632608 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ZkStateWriter.writePendingUpdates going to update_collection 
/collections/collection1/state.json version: 0
   [junit4]   2> 1632609 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader$7.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json for collection collection1 has 
occurred - updating... (live nodes size: 2)
   [junit4]   2> 1632610 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to 
ver 1 
   [junit4]   2> 1633608 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for 
collection1
   [junit4]   2> 1633609 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 1633609 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1633609 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.readConfigName Load collection config 
from:/collections/collection1
   [junit4]   2> 1633610 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.readConfigName path=/collections/collection1 
configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1633610 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1/'
   [junit4]   2> 1633624 T2638 n:127.0.0.1:31771_yc_wo%2Fl oasc.Config.<init> 
loaded config solrconfig.xml with version 0 
   [junit4]   2> 1633631 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
   [junit4]   2> 1633647 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
   [junit4]   2> 1633682 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1633683 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 1633690 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1634007 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1634009 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1634011 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oass.FileExchangeRateProvider.reload Reloading exchange rates from file 
currency.xml
   [junit4]   2> 1634014 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oass.FileExchangeRateProvider.reload Reloading exchange rates from file 
currency.xml
   [junit4]   2> 1634029 T2638 n:127.0.0.1:31771_yc_wo%2Fl 
oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration 
from collection collection1
   [junit4]   2> 1634029 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 1634032 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at 
[/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 1634032 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to 
JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@41199ec2
   [junit4]   2> 1634033 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.CachingDirectoryFactory.get return new directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1/data
   [junit4]   2> 1634034 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrCore.getNewIndexDir New index directory detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1/data/index/
   [junit4]   2> 1634035 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrCore.initIndex WARN [collection1] Solr index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1/data/index' doesn't exist. 
Creating new index...
   [junit4]   2> 1634035 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.CachingDirectoryFactory.get return new directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-1-001/cores/collection1/data/index
   [junit4]   2> 1634035 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@1c4d6fe6
   [junit4]   2> 1634040 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 
2C9192D375EDBFDD-001/shard-1-001/cores/collection1/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 1634040 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1634045 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 1634045 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 1634045 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1634046 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 1634046 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1634046 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"distrib-dup-test-chain-explicit"
   [junit4]   2> 1634046 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1634047 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1634047 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined 
as default, creating implicit default
   [junit4]   2> 1634060 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1634062 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1634064 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1634065 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1634071 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.RequestHandlers.initHandlersFromConfig Registered paths: 
/admin/plugins,standard,/update/json,/admin/file,/admin/system,/update/csv,/schema,/replication,/admin/ping,/update,/config,/admin/segments,/get,/admin/luke,/admin/mbeans,/admin/properties,/admin/threads,/admin/logging,/update/json/docs
   [junit4]   2> 1634073 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrCore.initStatsCache Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1634074 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasu.UpdateHandler.<init> Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1634074 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10
   [junit4]   2> 1634075 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1634075 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1634076 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1702138285, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1634077 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 
2C9192D375EDBFDD-001/shard-1-001/cores/collection1/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 1634077 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1634078 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oass.SolrIndexSearcher.<init> Opening Searcher@11cad418[collection1] main
   [junit4]   2> 1634078 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oascc.ZkStateReader.readConfigName Load collection config 
from:/collections/collection1
   [junit4]   2> 1634079 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oascc.ZkStateReader.readConfigName path=/collections/collection1 
configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1634079 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for 
the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1634079 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1634079 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 1634080 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage.load Reading _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1634080 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found 
for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1634080 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1634080 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 1634081 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 1634081 T2639 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.SolrCore.registerSearcher [collection1] Registered new searcher 
Searcher@11cad418[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1634082 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
   [junit4]   2> 1634082 T2638 n:127.0.0.1:31771_yc_wo%2Fl x:collection1 
oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1634083 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ZkController.register Register replica - 
core:collection1 address:http://127.0.0.1:31771/yc_wo/l collection:collection1 
shard:shard1
   [junit4]   2> 1634083 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2
   [junit4]   2> 1634083 T2578 n:127.0.0.1:31771_yc_wo%2Fl 
oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1634084 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: 
/collections/collection1/leader_elect/shard1/election
   [junit4]   2> 1634087 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running 
the leader process for shard shard1
   [junit4]   2> 1634088 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1634088 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp 
Enough replicas found to continue.
   [junit4]   2> 1634089 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may 
be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C867 name=collection1 
org.apache.solr.core.SolrCore@5c747608 
url=http://127.0.0.1:31771/yc_wo/l/collection1 node=127.0.0.1:31771_yc_wo%2Fl 
C867_STATE=coll:collection1 core:collection1 props:{core=collection1, 
base_url=http://127.0.0.1:31771/yc_wo/l, node_name=127.0.0.1:31771_yc_wo%2Fl, 
state=down}
   [junit4]   2> 1634089 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 C867 oasc.SyncStrategy.sync Sync replicas to 
http://127.0.0.1:31771/yc_wo/l/collection1/
   [junit4]   2> 1634089 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2>          "operation":"leader",
   [junit4]   2>          "shard":"shard1",
   [junit4]   2>          "collection":"collection1"} current state version: 5
   [junit4]   2> 1634089 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 C867 oasc.SyncStrategy.syncReplicas Sync Success - now 
sync replicas to me
   [junit4]   2> 1634089 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 C867 oasc.SyncStrategy.syncToMe 
http://127.0.0.1:31771/yc_wo/l/collection1/ has no replicas
   [junit4]   2> 1634090 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am 
the new leader: http://127.0.0.1:31771/yc_wo/l/collection1/ shard1
   [junit4]   2> 1634090 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: 
/collections/collection1/leaders/shard1
   [junit4]   2> 1634090 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ZkStateWriter.writePendingUpdates going to update_collection 
/collections/collection1/state.json version: 1
   [junit4]   2> 1634091 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader$7.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json for collection collection1 has 
occurred - updating... (live nodes size: 2)
   [junit4]   2> 1634091 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to 
ver 2 
   [junit4]   2> 1634093 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2>          "operation":"leader",
   [junit4]   2>          "shard":"shard1",
   [junit4]   2>          "collection":"collection1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31771/yc_wo/l";,
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "state":"active"} current state version: 5
   [junit4]   2> 1634094 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ZkStateWriter.writePendingUpdates going to update_collection 
/collections/collection1/state.json version: 2
   [junit4]   2> 1634094 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader$7.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json for collection collection1 has 
occurred - updating... (live nodes size: 2)
   [junit4]   2> 1634095 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to 
ver 3 
   [junit4]   2> 1634096 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1634143 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ZkController.register We are 
http://127.0.0.1:31771/yc_wo/l/collection1/ and leader is 
http://127.0.0.1:31771/yc_wo/l/collection1/
   [junit4]   2> 1634144 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for 
core=collection1 baseURL=http://127.0.0.1:31771/yc_wo/l
   [junit4]   2> 1634144 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no 
recovery necessary
   [junit4]   2> 1634144 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1 
state=active collection=collection1
   [junit4]   2> 1634144 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oasc.ZkController.publish numShards not found on 
descriptor - reading it from system property
   [junit4]   2> 1634146 T2606 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path 
/overseer/queue state SyncConnected
   [junit4]   2> 1634146 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "core_node_name":"core_node1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31771/yc_wo/l";,
   [junit4]   2>          "node_name":"127.0.0.1:31771_yc_wo%2Fl",
   [junit4]   2>          "shard":"shard1",
   [junit4]   2>          "collection":"collection1",
   [junit4]   2>          "numShards":"2",
   [junit4]   2>          "state":"active",
   [junit4]   2>          "operation":"state",
   [junit4]   2>          "roles":null} current state version: 5
   [junit4]   2> 1634147 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ReplicaMutator.updateState Update state numShards=2 message={
   [junit4]   2>          "core":"collection1",
   [junit4]   2>          "core_node_name":"core_node1",
   [junit4]   2>          "base_url":"http://127.0.0.1:31771/yc_wo/l";,
   [junit4]   2>          "node_name":"127.0.0.1:31771_yc_wo%2Fl",
   [junit4]   2>          "shard":"shard1",
   [junit4]   2>          "collection":"collection1",
   [junit4]   2>          "numShards":"2",
   [junit4]   2>          "state":"active",
   [junit4]   2>          "operation":"state",
   [junit4]   2>          "roles":null}
   [junit4]   2> 1634148 T2607 n:127.0.0.1:31764_yc_wo%2Fl 
oasco.ZkStateWriter.writePendingUpdates going to update_collection 
/collections/collection1/state.json version: 3
   [junit4]   2> 1634148 T2642 n:127.0.0.1:31771_yc_wo%2Fl c:collection1 
s:shard1 x:collection1 oascc.ZkStateReader.updateWatchedCollection Updating 
data for collection1 to ver 3 
   [junit4]   2> 1634148 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader$7.process A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json for collection collection1 has 
occurred - updating... (live nodes size: 2)
   [junit4]   2> 1634149 T2637 n:127.0.0.1:31771_yc_wo%2Fl 
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to 
ver 4 
   [junit4]   2> 1635220 T2578 oas.SolrTestCaseJ4.writeCoreProperties Writing 
core.properties file to 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/cores/collection1
   [junit4]   2> 1635222 T2578 oasc.AbstractFullDistribZkTestBase.createJettys 
create jetty 2 in directory 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001
   [junit4]   2> 1635223 T2578 oejs.Server.doStart jetty-9.2.10.v20150310
   [junit4]   2> 1635225 T2578 oejsh.ContextHandler.doStart Started 
o.e.j.s.ServletContextHandler@4530022f{/yc_wo/l,null,AVAILABLE}
   [junit4]   2> 1635225 T2578 oejs.AbstractConnector.doStart Started 
ServerConnector@4f1480a4{HTTP/1.1}{127.0.0.1:31800}
   [junit4]   2> 1635225 T2578 oejs.Server.doStart Started @1640656ms
   [junit4]   2> 1635226 T2578 oascse.JettySolrRunner$1.lifeCycleStarted Jetty 
properties: {solrconfig=solrconfig.xml, 
coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/cores, hostContext=/yc_wo/l, 
solr.data.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/tempDir-001/jetty2, hostPort=31800}
   [junit4]   2> 1635226 T2578 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@3972aa3f
   [junit4]   2> 1635226 T2578 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/'
   [junit4]   2> 1635259 T2578 oasc.SolrXmlConfig.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/solr.xml
   [junit4]   2> 1635298 T2578 oasc.CorePropertiesLocator.<init> Config-defined 
core root directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/cores
   [junit4]   2> 1635299 T2578 oasc.CoreContainer.<init> New CoreContainer 
1919939253
   [junit4]   2> 1635299 T2578 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/]
   [junit4]   2> 1635299 T2578 oasc.CoreContainer.load loading shared library: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/lib
   [junit4]   2> 1635300 T2578 oasc.SolrResourceLoader.addToClassLoader WARN 
Can't find (or read) directory to add to classloader: lib (resolved as: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/lib).
   [junit4]   2> 1635310 T2578 oashc.HttpShardHandlerFactory.init created with 
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : 
false,useRetries : false,
   [junit4]   2> 1635315 T2578 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1635315 T2578 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1635316 T2578 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1635316 T2578 oasc.CoreContainer.load Node Name: 127.0.0.1
   [junit4]   2> 1635316 T2578 oasc.ZkContainer.initZooKeeper Zookeeper 
client=127.0.0.1:31761/solr
   [junit4]   2> 1635316 T2578 oasc.ZkController.checkChrootPath zkHost 
includes chroot
   [junit4]   2> 1635317 T2578 
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default 
ZkCredentialsProvider
   [junit4]   2> 1635317 T2578 oascc.ConnectionManager.waitForConnected Waiting 
for client to connect to ZooKeeper
   [ju

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

build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest 
2C9192D375EDBFDD-001/shard-2-001/cores/collection1/data/index 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/cores/collection1/data/index;done=false>>]
   [junit4]   2> 2079880 T2651 n:127.0.0.1:31800_yc_wo%2Fl c:collection1 
s:shard2 r:core_node2 x:collection1 C1575 oasc.CachingDirectoryFactory.close 
Closing directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest
 2C9192D375EDBFDD-001/shard-2-001/cores/collection1/data/index
   [junit4]   2> 452475 T2577 ccr.ThreadLeakControl.tryToInterruptAll All 
leaked threads terminated.
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): 
{id=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
a_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
rnd_b=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_t=FST50, 
_version_=PostingsFormat(name=Direct)}, docValues:{}, sim=DefaultSimilarity, 
locale=nl_BE, timezone=America/Santa_Isabel
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 
(64-bit)/cpus=16,threads=1,free=177618296,total=258998272
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestManagedSynonymFilterFactory, OverseerRolesTest, TestExceedMaxTermLength, 
TestDynamicFieldCollectionResource, DistribDocExpirationUpdateProcessorTest, 
TestRandomDVFaceting, HdfsLockFactoryTest, TestFieldResource, 
ConnectionManagerTest, DistributedDebugComponentTest, 
TestCollapseQParserPlugin, SolrCoreCheckLockOnStartupTest, RankQueryTest, 
SuggestComponentTest, TestReplicationHandler, SolrPluginUtilsTest, 
TestManagedResource, TestQuerySenderNoQuery, TestMergePolicyConfig, 
LeaderElectionIntegrationTest, SSLMigrationTest, 
TestPHPSerializedResponseWriter, AnalyticsQueryTest, DistributedQueueTest, 
BlockCacheTest, BasicDistributedZk2Test, SliceStateTest, TestBinaryField, 
ChaosMonkeyNothingIsSafeTest]
   [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=ChaosMonkeyNothingIsSafeTest -Dtests.seed=2C9192D375EDBFDD 
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=nl_BE -Dtests.timezone=America/Santa_Isabel -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J2 | ChaosMonkeyNothingIsSafeTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ERROR: 
SolrIndexSearcher opens=31 closes=30
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([2C9192D375EDBFDD]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:496)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:232)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE 
scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest: 
   [junit4]    >    1) Thread[id=2651, name=qtp322780548-2651, state=WAITING, 
group=TGRP-ChaosMonkeyNothingIsSafeTest]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.lang.Object.wait(Object.java:503)
   [junit4]    >         at 
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397)
   [junit4]    >         at 
org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
   [junit4]    >         at 
org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229)
   [junit4]    >         at 
org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)
   [junit4]    >         at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782)
   [junit4]    >         at 
org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649)
   [junit4]    >         at 
org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
   [junit4]    >         at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
   [junit4]    >         at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
   [junit4]    >         at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2047)
   [junit4]    >         at 
org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
   [junit4]    >         at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
   [junit4]    >         at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
   [junit4]    >         at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]    >         at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
   [junit4]    >         at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]    >         at 
org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
   [junit4]    >         at 
org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)
   [junit4]    >         at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]    >         at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
   [junit4]    >         at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
   [junit4]    >         at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
   [junit4]    >         at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
   [junit4]    >         at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]    >         at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
   [junit4]    >         at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]    >         at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
   [junit4]    >         at 
org.eclipse.jetty.server.Server.handle(Server.java:497)
   [junit4]    >         at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
   [junit4]    >         at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
   [junit4]    >         at 
org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    2) Thread[id=2661, name=searcherExecutor-1417-thread-1, 
state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([2C9192D375EDBFDD]:0)
   [junit4] Completed [342/485] on J2 in 452.55s, 1 test, 1 failure, 1 error 
<<< FAILURES!

[...truncated 461 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:543:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:484:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:61:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/extra-targets.xml:39:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:229:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:511:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1433:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:991:
 There were test failures: 485 suites, 1949 tests, 2 suite-level errors, 65 
ignored (11 assumptions)

Total time: 163 minutes 43 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #828
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 713028033 bytes
Compression is 0.0%
Took 2 min 6 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to