lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-5.x-Java7 - Build # 2383 - Still Failing
Date Fri, 26 Dec 2014 09:25:39 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/2383/

1 tests failed.
REGRESSION:  org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.testDistribSearch

Error Message:
There were too many update fails - we expect it can happen, but shouldn't easily

Stack Trace:
java.lang.AssertionError: There were too many update fails - we expect it can happen, but shouldn't easily
	at __randomizedtesting.SeedInfo.seed([D36030A63CD25D63:5286BEBE4B8D3D5F]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertFalse(Assert.java:68)
	at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:223)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
	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:1618)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	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)




Build Log:
[...truncated 9484 lines...]
   [junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
   [junit4]   2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/init-core-data-001
   [junit4]   2> 731177 T1186 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /bs/gv
   [junit4]   2> 731182 T1186 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 731183 T1186 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 731183 T1187 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 731283 T1186 oasc.ZkTestServer.run start zk server on port:53879
   [junit4]   2> 731284 T1186 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
   [junit4]   2> 731284 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 731289 T1194 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@23e19613 name:ZooKeeperConnection Watcher:127.0.0.1:53879 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 731289 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 731289 T1186 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
   [junit4]   2> 731289 T1186 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 731292 T1186 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
   [junit4]   2> 731293 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 731295 T1197 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2c119bd9 name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 731295 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 731295 T1186 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
   [junit4]   2> 731296 T1186 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 731298 T1186 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 731299 T1186 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 731300 T1186 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 731301 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 731302 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 731304 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
   [junit4]   2> 731305 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 731407 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 731407 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 731409 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 731409 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 731411 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 731411 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 731413 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 731413 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 731415 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 731415 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 731416 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 731417 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 731418 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 731419 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 731420 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 731421 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 731422 T1186 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 731423 T1186 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 732368 T1186 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 732373 T1186 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:53885
   [junit4]   2> 732373 T1186 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@60da5686
   [junit4]   2> 732373 T1186 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 732374 T1186 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002
   [junit4]   2> 732374 T1186 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/'
   [junit4]   2> 732404 T1186 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/solr.xml
   [junit4]   2> 732458 T1186 oasc.CoreContainer.<init> New CoreContainer 1325555462
   [junit4]   2> 732459 T1186 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/]
   [junit4]   2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
   [junit4]   2> 732461 T1186 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 732461 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 732461 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 732461 T1186 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 732462 T1186 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 732462 T1186 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 732462 T1186 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 732463 T1186 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 732463 T1186 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 732463 T1186 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53879/solr
   [junit4]   2> 732463 T1186 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 732464 T1186 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
   [junit4]   2> 732464 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 732466 T1209 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@40a4d689 name:ZooKeeperConnection Watcher:127.0.0.1:53879 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 732467 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 732467 T1186 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
   [junit4]   2> 732469 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 732471 T1212 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@171e8560 name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 732471 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 732473 T1186 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 732475 T1186 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 732476 T1186 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
   [junit4]   2> 732478 T1186 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
   [junit4]   2> 732480 T1186 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
   [junit4]   2> 732482 T1186 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 732483 T1186 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53885_bs%2Fgv
   [junit4]   2> 732483 T1186 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53885_bs%2Fgv
   [junit4]   2> 732485 T1186 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 732486 T1186 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 732487 T1186 oasc.Overseer.close Overseer (id=null) closing
   [junit4]   2> 732489 T1186 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53885_bs%2Fgv
   [junit4]   2> 732489 T1186 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 732490 T1186 oasc.Overseer.start Overseer (id=93033784810799107-127.0.0.1:53885_bs%2Fgv-n_0000000000) starting
   [junit4]   2> 732493 T1186 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 732498 T1186 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 732499 T1214 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 732499 T1186 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 732502 T1186 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 732504 T1186 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 732511 T1213 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 732518 T1216 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
   [junit4]   2> 732519 T1216 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 732521 T1216 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 732521 T1212 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 732524 T1213 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = {
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53885/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53885_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"} current state version: 0
   [junit4]   2> 732524 T1213 oasco.ReplicaMutator.updateState Update state numShards=1 message={
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53885/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53885_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"}
   [junit4]   2> 732524 T1213 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
   [junit4]   2> 732525 T1213 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 732526 T1212 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> 733522 T1216 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 733523 T1216 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 733523 T1216 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 733524 T1216 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 733524 T1216 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 733525 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/'
   [junit4]   2> 733527 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/lib/README' to classloader
   [junit4]   2> 733528 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/lib/classes/' to classloader
   [junit4]   2> 733528 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/lib/.svn/' to classloader
   [junit4]   2> 733563 T1216 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
   [junit4]   2> 733565 T1216 oasc.Config.<init> loaded config solrconfig.xml with version 0 
   [junit4]   2> 733600 T1216 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
   [junit4]   2> 733648 T1216 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 733749 T1216 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 733761 T1216 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 734128 T1216 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 734131 T1216 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 734133 T1216 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 734137 T1216 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 734156 T1216 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 734157 T1216 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 734228 T1216 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/
   [junit4]   2> 734229 T1216 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@21993c80
   [junit4]   2> 734232 T1216 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data
   [junit4]   2> 734233 T1216 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index/
   [junit4]   2> 734234 T1216 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 734235 T1216 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index
   [junit4]   2> 734236 T1216 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2895872467303534]
   [junit4]   2> 734241 T1216 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 734241 T1216 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 734246 T1216 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 734246 T1216 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 734247 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 734247 T1216 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 734247 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 734248 T1216 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 734248 T1216 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 734248 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 734249 T1216 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 734249 T1216 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 734250 T1216 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 734250 T1216 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 734251 T1216 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 734251 T1216 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
   [junit4]   2> 734252 T1216 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
   [junit4]   2> 734252 T1216 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
   [junit4]   2> 734253 T1216 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
   [junit4]   2> 734253 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
   [junit4]   2> 734254 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
   [junit4]   2> 734255 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
   [junit4]   2> 734255 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
   [junit4]   2> 734256 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
   [junit4]   2> 734256 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
   [junit4]   2> 734257 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
   [junit4]   2> 734257 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
   [junit4]   2> 734258 T1216 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 734258 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 734258 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 734278 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 734280 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 734282 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 734284 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 734288 T1216 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 734288 T1216 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 734289 T1216 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27820321960034355]
   [junit4]   2> 734291 T1216 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 734291 T1216 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 734292 T1216 oass.SolrIndexSearcher.<init> Opening Searcher@59a26723[collection1] main
   [junit4]   2> 734292 T1216 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 734293 T1216 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 734293 T1216 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 734294 T1216 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 734294 T1216 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 734294 T1216 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 734295 T1216 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 734295 T1216 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 734295 T1216 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 734296 T1216 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 734297 T1217 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@59a26723[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 734297 T1216 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 734298 T1220 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53885/bs/gv collection:control_collection shard:shard1
   [junit4]   2> 734298 T1186 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1
   [junit4]   2> 734299 T1186 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 734299 T1220 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 734299 T1186 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
   [junit4]   2> 734300 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 734302 T1223 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@424e885b name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 734302 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 734303 T1186 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
   [junit4]   2> 734305 T1186 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 734305 T1220 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 734306 T1212 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 734306 T1220 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 734307 T1220 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C210 name=collection1 org.apache.solr.core.SolrCore@47da3c89 url=http://127.0.0.1:53885/bs/gv/collection1 node=127.0.0.1:53885_bs%2Fgv C210_STATE=coll:control_collection core:collection1 props:{core=collection1, state=down, base_url=http://127.0.0.1:53885/bs/gv, node_name=127.0.0.1:53885_bs%2Fgv}
   [junit4]   2> 734307 T1220 C210 P53885 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53885/bs/gv/collection1/
   [junit4]   2> 734307 T1213 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> 734307 T1186 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 734307 T1220 C210 P53885 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 734308 T1186 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2
   [junit4]   2> 734308 T1220 C210 P53885 oasc.SyncStrategy.syncToMe http://127.0.0.1:53885/bs/gv/collection1/ has no replicas
   [junit4]   2> 734308 T1186 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
   [junit4]   2> 734309 T1223 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> 734309 T1212 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> 734309 T1220 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53885/bs/gv/collection1/ shard1
   [junit4]   2> 734310 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 734310 T1220 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 734311 T1226 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@636a03ce name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 734312 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 734312 T1186 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
   [junit4]   2> 734314 T1227 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 734316 T1213 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = {
   [junit4]   2> 	  "operation":"create",
   [junit4]   2> 	  "name":"collection1",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "stateFormat":2} current state version: 2
   [junit4]   2> 734316 T1213 oasco.ClusterStateMutator.createCollection building a new cName: collection1
   [junit4]   2> 734318 T1213 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:53885/bs/gv",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "state":"active"} current state version: 2
   [junit4]   2> 734319 T1213 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json
   [junit4]   2> 734320 T1223 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> 734322 T1227 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 734323 T1223 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> 734366 T1220 oasc.ZkController.register We are http://127.0.0.1:53885/bs/gv/collection1/ and leader is http://127.0.0.1:53885/bs/gv/collection1/
   [junit4]   2> 734367 T1220 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53885/bs/gv
   [junit4]   2> 734367 T1220 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 734367 T1220 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
   [junit4]   2> 734367 T1220 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 734369 T1212 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 734370 T1213 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "core_node_name":"core_node1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53885/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53885_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"} current state version: 4
   [junit4]   2> 734371 T1213 oasco.ReplicaMutator.updateState Update state numShards=1 message={
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "core_node_name":"core_node1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53885/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53885_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"}
   [junit4]   2> 734474 T1212 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> 734474 T1223 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> 735598 T1186 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
   [junit4]   2> 735599 T1186 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 735601 T1186 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:53890
   [junit4]   2> 735602 T1186 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@60da5686
   [junit4]   2> 735602 T1186 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 735602 T1186 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003
   [junit4]   2> 735603 T1186 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/'
   [junit4]   2> 735636 T1186 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/solr.xml
   [junit4]   2> 735696 T1186 oasc.CoreContainer.<init> New CoreContainer 1863493584
   [junit4]   2> 735697 T1186 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/]
   [junit4]   2> 735698 T1186 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 735698 T1186 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 735698 T1186 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 735698 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
   [junit4]   2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 735700 T1186 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 735700 T1186 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 735700 T1186 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 735701 T1186 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 735701 T1186 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 735701 T1186 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53879/solr
   [junit4]   2> 735701 T1186 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 735702 T1186 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
   [junit4]   2> 735702 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 735704 T1239 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d2fe35c name:ZooKeeperConnection Watcher:127.0.0.1:53879 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 735705 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 735705 T1186 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
   [junit4]   2> 735707 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 735708 T1242 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@384fa2e4 name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 735709 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 735713 T1186 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 736716 T1186 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53890_bs%2Fgv
   [junit4]   2> 736717 T1186 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53890_bs%2Fgv
   [junit4]   2> 736720 T1186 oasc.Overseer.close Overseer (id=null) closing
   [junit4]   2> 736728 T1243 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 736728 T1243 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 736730 T1212 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 736730 T1243 oasc.ZkController.preRegister Registering watch for external collection collection1
   [junit4]   2> 736730 T1243 oascc.ZkStateReader.addZkWatch addZkWatch collection1
   [junit4]   2> 736730 T1213 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53890/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53890_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"} current state version: 5
   [junit4]   2> 736731 T1243 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... 
   [junit4]   2> 736731 T1213 oasco.ReplicaMutator.updateState Update state numShards=1 message={
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53890/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53890_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"}
   [junit4]   2> 736731 T1213 oasco.ReplicaMutator.updateState Collection already exists with numShards=1
   [junit4]   2> 736732 T1213 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 736732 T1243 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 
   [junit4]   2> 736732 T1243 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 736733 T1213 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0
   [junit4]   2> 736733 T1242 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... 
   [junit4]   2> 736734 T1242 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 
   [junit4]   2> 737732 T1243 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 737733 T1243 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 737733 T1243 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 737734 T1243 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 737734 T1243 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 737735 T1243 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/'
   [junit4]   2> 737736 T1243 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/lib/.svn/' to classloader
   [junit4]   2> 737737 T1243 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/lib/README' to classloader
   [junit4]   2> 737738 T1243 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/lib/classes/' to classloader
   [junit4]   2> 737771 T1243 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
   [junit4]   2> 737774 T1243 oasc.Config.<init> loaded config solrconfig.xml with version 0 
   [junit4]   2> 737809 T1243 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
   [junit4]   2> 737873 T1243 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 737974 T1243 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 737987 T1243 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 738374 T1243 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 738377 T1243 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 738379 T1243 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 738384 T1243 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 738402 T1243 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 738402 T1243 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 738403 T1243 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/
   [junit4]   2> 738403 T1243 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@21993c80
   [junit4]   2> 738405 T1243 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1
   [junit4]   2> 738406 T1243 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index/
   [junit4]   2> 738407 T1243 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
   [junit4]   2> 738408 T1243 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index
   [junit4]   2> 738409 T1243 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2895872467303534]
   [junit4]   2> 738440 T1243 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
   [junit4]   2> 738440 T1243 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 738446 T1243 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 738446 T1243 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 738447 T1243 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 738447 T1243 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 738447 T1243 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 738448 T1243 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 738448 T1243 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 738448 T1243 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 738449 T1243 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 738449 T1243 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 738450 T1243 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 738450 T1243 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 738451 T1243 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 738452 T1243 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
   [junit4]   2> 738452 T1243 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
   [junit4]   2> 738455 T1243 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
   [junit4]   2> 738455 T1243 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
   [junit4]   2> 738456 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
   [junit4]   2> 738457 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
   [junit4]   2> 738458 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
   [junit4]   2> 738459 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
   [junit4]   2> 738459 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
   [junit4]   2> 738460 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
   [junit4]   2> 738460 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
   [junit4]   2> 738461 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
   [junit4]   2> 738461 T1243 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 738462 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 738463 T1243 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 738481 T1243 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 738483 T1243 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 738485 T1243 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 738487 T1243 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 738491 T1243 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 738492 T1243 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 738494 T1243 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27820321960034355]
   [junit4]   2> 738495 T1243 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
   [junit4]   2> 738495 T1243 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 738496 T1243 oass.SolrIndexSearcher.<init> Opening Searcher@309ab835[collection1] main
   [junit4]   2> 738496 T1243 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 738497 T1243 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 738497 T1243 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 738498 T1243 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 738498 T1243 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 738498 T1243 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 738499 T1243 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 738499 T1243 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 738499 T1243 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 738500 T1243 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 738501 T1244 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@309ab835[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 738501 T1243 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 738502 T1247 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53890/bs/gv collection:collection1 shard:shard1
   [junit4]   2> 738502 T1186 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1
   [junit4]   2> 738503 T1186 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 738503 T1247 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
   [junit4]   2> 738507 T1247 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 738508 T1212 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 738509 T1247 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 738509 T1247 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C211 name=collection1 org.apache.solr.core.SolrCore@589e3679 url=http://127.0.0.1:53890/bs/gv/collection1 node=127.0.0.1:53890_bs%2Fgv C211_STATE=coll:collection1 core:collection1 props:{core=collection1, state=down, base_url=http://127.0.0.1:53890/bs/gv, node_name=127.0.0.1:53890_bs%2Fgv}
   [junit4]   2> 738509 T1247 C211 P53890 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53890/bs/gv/collection1/
   [junit4]   2> 738509 T1213 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> 738509 T1247 C211 P53890 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 738510 T1247 C211 P53890 oasc.SyncStrategy.syncToMe http://127.0.0.1:53890/bs/gv/collection1/ has no replicas
   [junit4]   2> 738510 T1247 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53890/bs/gv/collection1/ shard1
   [junit4]   2> 738510 T1247 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
   [junit4]   2> 738511 T1213 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1
   [junit4]   2> 738511 T1242 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... 
   [junit4]   2> 738512 T1242 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 
   [junit4]   2> 738514 T1213 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:53890/bs/gv",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "state":"active"} current state version: 5
   [junit4]   2> 738515 T1213 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2
   [junit4]   2> 738516 T1242 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... 
   [junit4]   2> 738516 T1242 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 
   [junit4]   2> 738517 T1212 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 738564 T1247 oasc.ZkController.register We are http://127.0.0.1:53890/bs/gv/collection1/ and leader is http://127.0.0.1:53890/bs/gv/collection1/
   [junit4]   2> 738565 T1247 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53890/bs/gv
   [junit4]   2> 738565 T1247 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 738565 T1247 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 738565 T1247 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 738567 T1212 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 738567 T1213 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "core_node_name":"core_node1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53890/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53890_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"} current state version: 5
   [junit4]   2> 738568 T1213 oasco.ReplicaMutator.updateState Update state numShards=1 message={
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "core_node_name":"core_node1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53890/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53890_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"}
   [junit4]   2> 738569 T1213 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3
   [junit4]   2> 738569 T1247 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 
   [junit4]   2> 738570 T1242 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... 
   [junit4]   2> 738570 T1242 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 
   [junit4]   2> 739607 T1186 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
   [junit4]   2> 739608 T1186 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 739610 T1186 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:53896
   [junit4]   2> 739611 T1186 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@60da5686
   [junit4]   2> 739611 T1186 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 739611 T1186 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004
   [junit4]   2> 739612 T1186 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/'
   [junit4]   2> 739641 T1186 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/solr.xml
   [junit4]   2> 739697 T1186 oasc.CoreContainer.<init> New CoreContainer 985638198
   [junit4]   2> 739697 T1186 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/]
   [junit4]   2> 739698 T1186 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 739698 T1186 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 739699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 739699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 739699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
   [junit4]   2> 739699 T1186 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 739700 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 739700 T1186 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 739700 T1186 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 739700 T1186 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 739701 T1186 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 739701 T1186 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 739701 T1186 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 739701 T1186 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 739702 T1186 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53879/solr
   [junit4]   2> 739702 T1186 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 739702 T1186 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
   [junit4]   2> 739703 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 739705 T1259 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b0de448 name:ZooKeeperConnection Watcher:127.0.0.1:53879 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 739706 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 739706 T1186 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
   [junit4]   2> 739709 T1186 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 739710 T1262 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15856359 name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 739710 T1186 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 739715 T1186 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 740718 T1186 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53896_bs%2Fgv
   [junit4]   2> 740719 T1186 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53896_bs%2Fgv
   [junit4]   2> 740722 T1186 oasc.Overseer.close Overseer (id=null) closing
   [junit4]   2> 740730 T1263 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 740730 T1263 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 740732 T1212 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 740732 T1263 oasc.ZkController.preRegister Registering watch for external collection collection1
   [junit4]   2> 740732 T1263 oascc.ZkStateReader.addZkWatch addZkWatch collection1
   [junit4]   2> 740732 T1213 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53896/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53896_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"} current state version: 5
   [junit4]   2> 740733 T1263 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... 
   [junit4]   2> 740733 T1213 oasco.ReplicaMutator.updateState Update state numShards=1 message={
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53896/bs/gv",
   [junit4]   2> 	  "node_name":"127.0.0.1:53896_bs%2Fgv",
   [junit4]   2> 	  "numShards":"1"}
   [junit4]   2> 740733 T1213 oasco.ReplicaMutator.updateState Collection already exists with numShards=1
   [junit4]   2> 740734 T1213 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 740734 T1263 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 
   [junit4]   2> 740734 T1263 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 740735 T1213 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4
   [junit4]   2> 740735 T1242 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... 
   [junit4]   2> 740735 T1262 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... 
   [junit4]   2> 740736 T1242 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 
   [junit4]   2> 740736 T1262 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 
   [junit4]   2> 741734 T1263 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 741735 T1263 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 741735 T1263 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 741735 T1263 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 741736 T1263 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 741736 T1263 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/'
   [junit4]   2> 741738 T1263 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/lib/README' to classloader
   [junit4]   2> 741739 T1263 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/lib/.svn/' to classloader
   [junit4]   2> 741739 T1263 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/lib/classes/' to classloader
   [junit4]   2> 741773 T1263 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
   [junit4]   2> 741775 T1263 oasc.Config.<init> loaded config solrconfig.xml with version 0 
   [junit4]   2> 741810 T1263 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
   [junit4]   2> 741857 T1263 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 741958 T1263 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 741971 T1263 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 742323 T1263 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 742326 T1263 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 742328 T1263 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 742332 T1263 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 742350 T1263 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 742351 T1263 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 742351 T1263 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/
   [junit4]   2> 742351 T1263 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@21993c80
   [junit4]   2> 742353 T1263 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2
   [junit4]   2> 742354 T1263 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index/
   [junit4]   2> 742354 T1263 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
   [junit4]   2> 742356 T1263 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index
   [junit4]   2> 742357 T1263 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2895872467303534]
   [junit4]   2> 742372 T1263 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
   [junit4]   2> 742372 T1263 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 742378 T1263 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 742379 T1263 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 742379 T1263 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 742379 T1263 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 742379 T1263 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 742380 T1263 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 742380 T1263 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 742381 T1263 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 742381 T1263 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 742382 T1263 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 742382 T1263 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 742383 T1263 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 742383 T1263 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 742384 T1263 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
   [junit4]   2> 742384 T1263 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
   [junit4]   2> 742387 T1263 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
   [junit4]   2> 742387 T1263 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
   [junit4]   2> 742388 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
   [junit4]   2> 742389 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
   [junit4]   2> 742389 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
   [junit4]   2> 742390 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
   [junit4]   2> 742390 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
   [junit4]   2> 742391 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
   [junit4]   2> 742392 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
   [junit4]   2> 742392 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
   [junit4]   2> 742393 T1263 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 742393 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 742394 T1263 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 742413 T1263 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 742415 T1263 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 742416 T1263 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 742418 T1263 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 742422 T1263 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 742422 T1263 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 742423 T1263 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27820321960034355]
   [junit4]   2> 742425 T1263 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
   [junit4]   2> 742425 T1263 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 742426 T1263 oass.SolrIndexSearcher.<init> Opening Searcher@3394e61f[collection1] main
   [junit4]   2> 742426 T1263 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 742427 T1263 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 742427 T1263 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 742428 T1263 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 742428 T1263 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 742428 T1263 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 742429 T1263 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 742429 T1263 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 742429 T1263 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 742429 T1263 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 742430 T1264 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3394e61f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 742431 T1263 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 742432 T1267 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53896/bs/gv collection:collection1 shard:shard1
   [junit4]   2> 742432 T1186 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1
   [junit4]   2> 742432 T1186 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 742434 T1267 oasc.ZkController.register We are http://127.0.0.1:53896/bs/gv/collection1/ and leader is http://127.0.0.1:53890/bs/gv/collection1/
   [junit4]   2> 742434 T1267 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53896/bs/gv
   [junit4]   2> 742434 T1267 oasc.ZkController.checkRecovery Core needs to recover:collection1
   [junit4]   2> 742434 T1267 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
   [junit4]   2> ASYNC  NEW_CORE C212 name=collection1 org.apache.solr.core.SolrCore@718b939f url=http://127.0.0.1:53896/bs/gv/collection1 node=127.0.0.1:53896_bs%2Fgv C212_STATE=coll:collection1 core:collection1 props:{core=collection1, state=down, base_url=http://127.0.0.1:53896/bs/gv, node_name=127.0.0.1:53896_bs%2Fgv}
   [junit4]   2> 742435 T1268 C212 P53896 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
   [junit4]   2> 742436 T1268 C212 P53896 oasc.RecoveryStrategy.doRecovery ###### startupVe

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

dLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 825490 T1186 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 825490 T1186 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index;done=false>>]
   [junit4]   2> 825491 T1186 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index
   [junit4]   2> 825491 T1186 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2 [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2;done=false>>]
   [junit4]   2> 825491 T1186 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2
   [junit4]   2> 825492 T1186 oasc.Overseer.close Overseer (id=93033784810799113-127.0.0.1:53896_bs%2Fgv-n_0000000002) closing
   [junit4]   2> 825493 T1286 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:53896_bs%2Fgv
   [junit4]   2> 826993 T1282 oascc.ZkStateReader$7.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 826994 T1284 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 826996 T1186 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/bs/gv,null}
   [junit4]   2> 831997 T1186 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
   [junit4]   2> 831999 T1186 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:53879 53879
   [junit4]   2> 832112 T1187 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:53879 53879
   [junit4]   2> 832115 T1187 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: 
   [junit4]   2> 	Maximum concurrent create/delete watches above limit:
   [junit4]   2> 	
   [junit4]   2> 		4	/solr/aliases.json
   [junit4]   2> 		4	/solr/clusterstate.json
   [junit4]   2> 		3	/solr/configs/conf1
   [junit4]   2> 		2	/solr/collections/collection1/state.json
   [junit4]   2> 	
   [junit4]   2> 	Maximum concurrent data watches above limit:
   [junit4]   2> 	
   [junit4]   2> 		2	/solr/overseer_elect/election/93033784810799107-127.0.0.1:53885_bs%2Fgv-n_0000000000
   [junit4]   2> 	
   [junit4]   2> 	Maximum concurrent children watches above limit:
   [junit4]   2> 	
   [junit4]   2> 		4	/solr/live_nodes
   [junit4]   2> 		2	/solr/overseer/collection-queue-work
   [junit4]   2> 		2	/solr/overseer/queue
   [junit4]   2> 	
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ChaosMonkeyNothingIsSafeTest -Dtests.method=testDistribSearch -Dtests.seed=D36030A63CD25D63 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=Asia/Jakarta -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE  101s J1 | ChaosMonkeyNothingIsSafeTest.testDistribSearch <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: There were too many update fails - we expect it can happen, but shouldn't easily
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([D36030A63CD25D63:5286BEBE4B8D3D5F]:0)
   [junit4]    > 	at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:223)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 832128 T1186 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001
   [junit4]   2> NOTE: test params are: codec=Lucene50, sim=DefaultSimilarity, locale=vi, timezone=Asia/Jakarta
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=95258688,total=296747008
   [junit4]   2> NOTE: All tests run in this JVM: [TestWordDelimiterFilterFactory, TestFieldTypeCollectionResource, TestFileDictionaryLookup, DirectSolrSpellCheckerTest, TestHighFrequencyDictionaryFactory, MultiThreadedOCPTest, DistributedFacetPivotLargeTest, BasicFunctionalityTest, DistanceFunctionTest, TestQueryUtils, TestSerializedLuceneMatchVersion, SoftAutoCommitTest, PreAnalyzedUpdateProcessorTest, TestPerFieldSimilarity, TestIndexingPerformance, TestDistributedSearch, CoreMergeIndexesAdminHandlerTest, TimeZoneUtilsTest, ClusterStateUpdateTest, TestManagedSchemaFieldTypeResource, SSLMigrationTest, LeaderFailoverAfterPartitionTest, OutputWriterTest, TestShardHandlerFactory, DistributedFacetPivotLongTailTest, ChaosMonkeyNothingIsSafeTest]
   [junit4] Completed on J1 in 101.00s, 1 test, 1 failure <<< FAILURES!

[...truncated 870 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:529: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:477: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:190: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:508: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1363: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:970: There were test failures: 453 suites, 1855 tests, 1 failure, 61 ignored (23 assumptions)

Total time: 61 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #2381
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 187968352 bytes
Compression is 0.0%
Took 36 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message