lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.8.0_20-ea-b11) - Build # 3961 - Failure!
Date Mon, 19 May 2014 04:39:55 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3961/
Java: 64bit/jdk1.8.0_20-ea-b11 -XX:-UseCompressedOops -XX:+UseG1GC

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

Error Message:
expected:<0> but was:<1>

Stack Trace:
java.lang.AssertionError: expected:<0> but was:<1>
	at __randomizedtesting.SeedInfo.seed([96E3644E33657736:1705EA56443A170A]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.doTest(ChaosMonkeySafeLeaderTest.java:141)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
	at sun.reflect.GeneratedMethodAccessor58.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:483)
	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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	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:360)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
	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:43)
	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:360)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11334 lines...]
   [junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
   [junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\init-core-data-001
   [junit4]   2> 1565424 T6272 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
   [junit4]   2> 1565424 T6272 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /tvy/t
   [junit4]   2> 1565432 T6272 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 1565434 T6272 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1565435 T6273 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 1565543 T6272 oasc.ZkTestServer.run start zk server on port:65484
   [junit4]   2> 1565546 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1565555 T6279 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c7e68ce name:ZooKeeperConnection Watcher:127.0.0.1:65484 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1565555 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1565556 T6272 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 1565565 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1565571 T6281 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5f2594ed name:ZooKeeperConnection Watcher:127.0.0.1:65484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1565571 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1565571 T6272 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 1565577 T6272 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 1565582 T6272 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 1565586 T6272 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 1565593 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1565593 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1565603 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml to /configs/conf1/schema.xml
   [junit4]   2> 1565603 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 1565611 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1565611 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1565617 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1565617 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1565625 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1565625 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1565633 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1565634 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 1565641 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1565642 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1565649 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1565649 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1565655 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1565655 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1565662 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1565662 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1565667 T6272 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1565667 T6272 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1565676 T6274 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14612a921e90001, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1565678 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1565681 T6283 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@50d282c5 name:ZooKeeperConnection Watcher:127.0.0.1:65484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1565682 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1568840 T6272 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1568861 T6272 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 1568885 T6272 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:65494
   [junit4]   2> 1568893 T6272 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1568894 T6272 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1568894 T6272 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-002
   [junit4]   2> 1568894 T6272 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-002\'
   [junit4]   2> 1568964 T6272 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-002\solr.xml
   [junit4]   2> 1568991 T6272 oasc.CoreContainer.<init> New CoreContainer 1986279881
   [junit4]   2> 1568991 T6272 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-002\]
   [junit4]   2> 1568995 T6272 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1568995 T6272 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1568995 T6272 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1568995 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1568995 T6272 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1568995 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1568995 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1568995 T6272 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1568997 T6272 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1569011 T6272 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1569011 T6272 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1569011 T6272 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1569011 T6272 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65484/solr
   [junit4]   2> 1569011 T6272 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1569017 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1569024 T6294 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b1bb2a0 name:ZooKeeperConnection Watcher:127.0.0.1:65484 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1569025 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1569037 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1569041 T6296 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4beef4c7 name:ZooKeeperConnection Watcher:127.0.0.1:65484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1569042 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1569047 T6272 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 1569055 T6272 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 1569064 T6272 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
   [junit4]   2> 1569072 T6272 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
   [junit4]   2> 1569079 T6272 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
   [junit4]   2> 1569096 T6272 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 1569103 T6272 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65494_tvy%2Ft
   [junit4]   2> 1569105 T6272 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:65494_tvy%2Ft
   [junit4]   2> 1569112 T6272 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 1569118 T6272 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 1569128 T6272 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:65494_tvy%2Ft
   [junit4]   2> 1569128 T6272 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 1569135 T6272 oasc.Overseer.start Overseer (id=91781360035364868-127.0.0.1:65494_tvy%2Ft-n_0000000000) starting
   [junit4]   2> 1569143 T6272 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 1569176 T6298 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 1569178 T6272 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 1569191 T6272 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 1569195 T6272 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1569204 T6297 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 1569214 T6299 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
   [junit4]   2> 1569214 T6299 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 1569217 T6299 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1569218 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1569222 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1569223 T6297 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:65494/tvy/t",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:65494_tvy%2Ft",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 1569223 T6297 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
   [junit4]   2> 1569223 T6297 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 1569233 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1569236 T6296 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> 1570403 T6299 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1570403 T6299 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 1570404 T6299 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1570404 T6299 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 1570407 T6299 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1570407 T6299 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-002\collection1\'
   [junit4]   2> 1570409 T6299 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-002/collection1/lib/.svn/' to classloader
   [junit4]   2> 1570409 T6299 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-002/collection1/lib/classes/' to classloader
   [junit4]   2> 1570411 T6299 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-002/collection1/lib/README' to classloader
   [junit4]   2> 1570456 T6299 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 1570470 T6299 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1570475 T6299 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1570480 T6299 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1570657 T6299 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1570660 T6299 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1570661 T6299 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1570666 T6299 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1570704 T6299 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1570704 T6299 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 1570751 T6299 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-002\collection1\, dataDir=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001/control/data\
   [junit4]   2> 1570751 T6299 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5953fb75
   [junit4]   2> 1570752 T6299 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\control\data
   [junit4]   2> 1570753 T6299 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001/control/data\index/
   [junit4]   2> 1570753 T6299 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\control\data\index' doesn't exist. Creating new index...
   [junit4]   2> 1570753 T6299 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\control\data\index
   [junit4]   2> 1570755 T6299 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1570764 T6299 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\control\data\index,segFN=segments_1,generation=1}
   [junit4]   2> 1570764 T6299 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1570768 T6299 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1570768 T6299 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1570768 T6299 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1570769 T6299 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1570770 T6299 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1570770 T6299 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1570770 T6299 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1570771 T6299 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1570771 T6299 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1570771 T6299 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1570773 T6299 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1570773 T6299 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1570773 T6299 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1570773 T6299 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1570774 T6299 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1570774 T6299 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1570781 T6299 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1570784 T6299 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1570784 T6299 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1570785 T6299 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1570786 T6299 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\control\data\index,segFN=segments_1,generation=1}
   [junit4]   2> 1570786 T6299 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1570788 T6299 oass.SolrIndexSearcher.<init> Opening Searcher@3c697db[collection1] main
   [junit4]   2> 1570788 T6299 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 1570791 T6299 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1570791 T6299 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1570792 T6299 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1570792 T6299 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 1570792 T6299 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1570793 T6299 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1570793 T6299 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1570793 T6299 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 1570793 T6299 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 1570797 T6300 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c697db[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1570797 T6299 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1570799 T6303 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:65494/tvy/t collection:control_collection shard:shard1
   [junit4]   2> 1570800 T6272 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1570802 T6272 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1570802 T6303 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 1570815 T6303 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 1570820 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1570822 T6303 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 1570822 T6303 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C1044 name=collection1 org.apache.solr.core.SolrCore@1df06dc1 url=https://127.0.0.1:65494/tvy/t/collection1 node=127.0.0.1:65494_tvy%2Ft C1044_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:65494/tvy/t, core=collection1, node_name=127.0.0.1:65494_tvy%2Ft}
   [junit4]   2> 1570822 T6303 C1044 P65494 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:65494/tvy/t/collection1/
   [junit4]   2> 1570824 T6303 C1044 P65494 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 1570824 T6303 C1044 P65494 oasc.SyncStrategy.syncToMe https://127.0.0.1:65494/tvy/t/collection1/ has no replicas
   [junit4]   2> 1570824 T6303 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:65494/tvy/t/collection1/ shard1
   [junit4]   2> 1570826 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1570827 T6303 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1570828 T6305 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@46c86b5e name:ZooKeeperConnection Watcher:127.0.0.1:65484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1570828 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1570829 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1570830 T6272 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1570837 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1570839 T6272 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1570847 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1570855 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1570990 T6305 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> 1570994 T6296 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> 1571041 T6303 oasc.ZkController.register We are https://127.0.0.1:65494/tvy/t/collection1/ and leader is https://127.0.0.1:65494/tvy/t/collection1/
   [junit4]   2> 1571059 T6303 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:65494/tvy/t
   [junit4]   2> 1571060 T6303 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 1571060 T6303 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
   [junit4]   2> 1571060 T6303 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 1571063 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1571063 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1571063 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1571067 T6303 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1571070 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1571071 T6297 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:65494/tvy/t",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:65494_tvy%2Ft",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 1571078 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1571206 T6305 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> 1571206 T6296 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> 1573604 T6272 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
   [junit4]   2> 1573605 T6272 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1573617 T6272 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 1573620 T6272 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:65506
   [junit4]   2> 1573627 T6272 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1573627 T6272 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1573627 T6272 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-003
   [junit4]   2> 1573627 T6272 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-003\'
   [junit4]   2> 1573686 T6272 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-003\solr.xml
   [junit4]   2> 1573708 T6272 oasc.CoreContainer.<init> New CoreContainer 528823561
   [junit4]   2> 1573708 T6272 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-003\]
   [junit4]   2> 1573712 T6272 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1573712 T6272 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1573712 T6272 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1573712 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1573712 T6272 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1573712 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1573712 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1573712 T6272 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1573715 T6272 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1573748 T6272 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1573748 T6272 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1573748 T6272 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1573748 T6272 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65484/solr
   [junit4]   2> 1573748 T6272 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1573755 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1573831 T6316 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6c53f574 name:ZooKeeperConnection Watcher:127.0.0.1:65484 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1573832 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1573842 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1573846 T6318 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2bcfaa6c name:ZooKeeperConnection Watcher:127.0.0.1:65484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1573847 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1573873 T6272 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1575067 T6272 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65506_tvy%2Ft
   [junit4]   2> 1575070 T6272 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:65506_tvy%2Ft
   [junit4]   2> 1575078 T6318 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 1575078 T6305 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 1575080 T6296 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 1575088 T6319 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 1575089 T6319 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 1575090 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1575090 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1575091 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1575091 T6319 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1575093 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1575095 T6297 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:65506/tvy/t",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:65506_tvy%2Ft",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 1575095 T6297 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3]
   [junit4]   2> 1575095 T6297 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 1575102 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1575103 T6305 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1575103 T6296 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1575103 T6318 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1576278 T6319 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1576278 T6319 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 1576279 T6319 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1576279 T6319 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 1576282 T6319 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1576282 T6319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-003\collection1\'
   [junit4]   2> 1576284 T6319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-003/collection1/lib/.svn/' to classloader
   [junit4]   2> 1576284 T6319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-003/collection1/lib/classes/' to classloader
   [junit4]   2> 1576286 T6319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-003/collection1/lib/README' to classloader
   [junit4]   2> 1576335 T6319 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 1576349 T6319 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1576351 T6319 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1576359 T6319 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1576540 T6319 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1576542 T6319 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1576543 T6319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1576548 T6319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1576578 T6319 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 1576578 T6319 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 1576579 T6319 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-003\collection1\, dataDir=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001/jetty1\
   [junit4]   2> 1576579 T6319 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5953fb75
   [junit4]   2> 1576579 T6319 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty1
   [junit4]   2> 1576583 T6319 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001/jetty1\index/
   [junit4]   2> 1576583 T6319 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty1\index' doesn't exist. Creating new index...
   [junit4]   2> 1576583 T6319 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty1\index
   [junit4]   2> 1576584 T6319 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1576590 T6319 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1}
   [junit4]   2> 1576590 T6319 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1576594 T6319 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1576594 T6319 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1576594 T6319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1576595 T6319 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1576595 T6319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1576595 T6319 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1576595 T6319 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1576597 T6319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1576597 T6319 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1576597 T6319 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1576598 T6319 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1576598 T6319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1576598 T6319 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1576599 T6319 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1576599 T6319 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1576599 T6319 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1576606 T6319 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1576608 T6319 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1576608 T6319 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1576611 T6319 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1576613 T6319 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1}
   [junit4]   2> 1576613 T6319 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1576613 T6319 oass.SolrIndexSearcher.<init> Opening Searcher@5c3a4716[collection1] main
   [junit4]   2> 1576613 T6319 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 1576628 T6319 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1576628 T6319 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1576629 T6319 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1576629 T6319 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 1576629 T6319 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1576630 T6319 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1576630 T6319 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1576630 T6319 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 1576630 T6319 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 1576635 T6319 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1576635 T6320 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5c3a4716[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1576638 T6323 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:65506/tvy/t collection:collection1 shard:shard2
   [junit4]   2> 1576639 T6272 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1576639 T6272 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1576664 T6323 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
   [junit4]   2> 1576915 T6323 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
   [junit4]   2> 1576920 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1576922 T6323 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 1576922 T6323 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C1045 name=collection1 org.apache.solr.core.SolrCore@294a01ef url=https://127.0.0.1:65506/tvy/t/collection1 node=127.0.0.1:65506_tvy%2Ft C1045_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:65506/tvy/t, core=collection1, node_name=127.0.0.1:65506_tvy%2Ft}
   [junit4]   2> 1576922 T6323 C1045 P65506 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:65506/tvy/t/collection1/
   [junit4]   2> 1576922 T6323 C1045 P65506 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 1576923 T6323 C1045 P65506 oasc.SyncStrategy.syncToMe https://127.0.0.1:65506/tvy/t/collection1/ has no replicas
   [junit4]   2> 1576923 T6323 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:65506/tvy/t/collection1/ shard2
   [junit4]   2> 1576923 T6323 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
   [junit4]   2> 1576930 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1576941 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1576943 T6318 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1576945 T6305 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1576946 T6296 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1576956 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1576964 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1577085 T6318 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1577087 T6305 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1577088 T6296 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1577137 T6323 oasc.ZkController.register We are https://127.0.0.1:65506/tvy/t/collection1/ and leader is https://127.0.0.1:65506/tvy/t/collection1/
   [junit4]   2> 1577137 T6323 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:65506/tvy/t
   [junit4]   2> 1577137 T6323 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 1577137 T6323 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 1577137 T6323 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 1577162 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1577162 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1577163 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1577163 T6323 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1577169 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1577170 T6297 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:65506/tvy/t",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:65506_tvy%2Ft",
   [junit4]   2> 	  "shard":"shard2",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 1577176 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1577302 T6318 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1577305 T6305 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1577307 T6296 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1579561 T6272 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
   [junit4]   2> 1579561 T6272 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1579581 T6272 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 1579585 T6272 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:65515
   [junit4]   2> 1579591 T6272 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1579594 T6272 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1579594 T6272 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-004
   [junit4]   2> 1579594 T6272 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-004\'
   [junit4]   2> 1579653 T6272 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-004\solr.xml
   [junit4]   2> 1579675 T6272 oasc.CoreContainer.<init> New CoreContainer 1577877873
   [junit4]   2> 1579675 T6272 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-004\]
   [junit4]   2> 1579678 T6272 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1579678 T6272 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1579678 T6272 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1579678 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1579678 T6272 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1579678 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1579678 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1579678 T6272 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1579682 T6272 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1579695 T6272 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1579695 T6272 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1579695 T6272 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1579695 T6272 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65484/solr
   [junit4]   2> 1579698 T6272 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1579703 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1579724 T6334 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@541ad106 name:ZooKeeperConnection Watcher:127.0.0.1:65484 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1579724 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1579729 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1579736 T6336 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14ff33f4 name:ZooKeeperConnection Watcher:127.0.0.1:65484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1579736 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1579757 T6272 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1580853 T6272 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65515_tvy%2Ft
   [junit4]   2> 1580855 T6272 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:65515_tvy%2Ft
   [junit4]   2> 1580862 T6318 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 1580862 T6305 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 1580863 T6296 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 1580863 T6336 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 1580878 T6337 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 1580878 T6337 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 1580880 T6337 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1580880 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1580881 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1580881 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1580884 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1580885 T6297 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:65515/tvy/t",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:65515_tvy%2Ft",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 1580885 T6297 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
   [junit4]   2> 1580885 T6297 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
   [junit4]   2> 1580891 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1580892 T6305 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1580893 T6336 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1580893 T6296 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1580893 T6318 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1581947 T6337 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1581947 T6337 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 1581948 T6337 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1581948 T6337 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 1581950 T6337 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1581950 T6337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-004\collection1\'
   [junit4]   2> 1581953 T6337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-004/collection1/lib/.svn/' to classloader
   [junit4]   2> 1581953 T6337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-004/collection1/lib/classes/' to classloader
   [junit4]   2> 1581953 T6337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001/tempDir-004/collection1/lib/README' to classloader
   [junit4]   2> 1581997 T6337 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 1582008 T6337 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1582011 T6337 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1582015 T6337 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 1582164 T6337 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1582164 T6337 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1582168 T6337 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1582172 T6337 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1582213 T6337 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 1582213 T6337 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 1582214 T6337 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-004\collection1\, dataDir=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001/jetty2\
   [junit4]   2> 1582214 T6337 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5953fb75
   [junit4]   2> 1582214 T6337 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty2
   [junit4]   2> 1582217 T6337 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001/jetty2\index/
   [junit4]   2> 1582217 T6337 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty2\index' doesn't exist. Creating new index...
   [junit4]   2> 1582217 T6337 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty2\index
   [junit4]   2> 1582218 T6337 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1582225 T6337 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1}
   [junit4]   2> 1582225 T6337 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1582229 T6337 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1582229 T6337 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1582230 T6337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1582230 T6337 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1582230 T6337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1582230 T6337 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1582231 T6337 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1582231 T6337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1582231 T6337 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1582232 T6337 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1582232 T6337 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1582232 T6337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1582232 T6337 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1582233 T6337 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1582233 T6337 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1582234 T6337 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1582238 T6337 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1582243 T6337 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1582243 T6337 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1582244 T6337 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1582244 T6337 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1}
   [junit4]   2> 1582244 T6337 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1582247 T6337 oass.SolrIndexSearcher.<init> Opening Searcher@465a16dd[collection1] main
   [junit4]   2> 1582247 T6337 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 1582249 T6337 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1582249 T6337 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1582251 T6337 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1582251 T6337 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 1582251 T6337 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1582252 T6337 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1582252 T6337 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1582252 T6337 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 1582252 T6337 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 1582256 T6338 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@465a16dd[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1582258 T6337 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1582260 T6341 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:65515/tvy/t collection:collection1 shard:shard3
   [junit4]   2> 1582260 T6272 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1582261 T6272 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1582262 T6341 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
   [junit4]   2> 1582277 T6341 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
   [junit4]   2> 1582283 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1582284 T6341 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 1582284 T6341 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C1046 name=collection1 org.apache.solr.core.SolrCore@21a5e66a url=https://127.0.0.1:65515/tvy/t/collection1 node=127.0.0.1:65515_tvy%2Ft C1046_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:65515/tvy/t, core=collection1, node_name=127.0.0.1:65515_tvy%2Ft}
   [junit4]   2> 1582285 T6341 C1046 P65515 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:65515/tvy/t/collection1/
   [junit4]   2> 1582285 T6341 C1046 P65515 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 1582285 T6341 C1046 P65515 oasc.SyncStrategy.syncToMe https://127.0.0.1:65515/tvy/t/collection1/ has no replicas
   [junit4]   2> 1582285 T6341 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:65515/tvy/t/collection1/ shard3
   [junit4]   2> 1582286 T6341 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
   [junit4]   2> 1582291 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1582304 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1582310 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1582319 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1582432 T6318 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1582433 T6305 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1582434 T6336 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1582435 T6296 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1582477 T6341 oasc.ZkController.register We are https://127.0.0.1:65515/tvy/t/collection1/ and leader is https://127.0.0.1:65515/tvy/t/collection1/
   [junit4]   2> 1582477 T6341 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:65515/tvy/t
   [junit4]   2> 1582477 T6341 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 1582477 T6341 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 1582477 T6341 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 1582480 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1582480 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1582480 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1582482 T6341 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1582484 T6297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1582485 T6297 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:65515/tvy/t",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:65515_tvy%2Ft",
   [junit4]   2> 	  "shard":"shard3",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":"core_node2"}
   [junit4]   2> 1582490 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1582604 T6305 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1582604 T6336 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1582605 T6296 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1582605 T6318 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 1584601 T6272 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
   [junit4]   2> 1584602 T6272 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1584619 T6272 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 1584622 T6272 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:65524
   [junit4]   2> 1584629 T6272 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1584629 T6272 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1584629 T6272 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-005
   [junit4]   2> 1584629 T6272 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-005\'
   [junit4]   2> 1584683 T6272 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-005\solr.xml
   [junit4]   2> 1584702 T6272 oasc.CoreContainer.<init> New CoreContainer 975530458
   [junit4]   2> 1584702 T6272 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-005\]
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1584705 T6272 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1584713 T6272 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1584713 T6272 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1584713 T6272 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1584715 T6272 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65484/solr
   [junit4]   2> 1584715 T6272 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1584724 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1584734 T6352 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3dac3f03 name:ZooKeeperConnection Watcher:127.0.0.1:65484 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1584734 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1584737 T6274 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14612a921e9000a, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1584741 T6272 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1584744 T6354 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ab37e2f name:ZooKeeperConnection Watcher:127.0.0.1:65484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1584745 T6272 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1584768 T6272 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1585844 T6272 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65524_tvy%2Ft
   [junit4]   2> 1585849 T6272 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:65524_tvy%2Ft
   [junit4]   2> 1585861 T6318 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 1585863 T6354 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 1585863 T6305 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 1585864 T6336 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 1585864 T6296 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 1585884 T6355 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 1585884 T6355 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 1585886 T6296 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1585887 T6296 oasc.DistributedQueue$LatchChildWatcher.

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

t4]   2> 1724861 T6742 oasc.LeaderElector$ElectionWatcher.process WARN  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]   2> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:260)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:257)
   [junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:257)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:94)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
   [junit4]   2> 
   [junit4]   2> 1724864 T6807 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1724865 T6807 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1724865 T6807 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty5 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty5;done=false>>]
   [junit4]   2> 1724865 T6807 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty5
   [junit4]   2> 1724865 T6807 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty5\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty5\index;done=false>>]
   [junit4]   2> 1724866 T6807 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001\tempDir-001\jetty5\index
   [junit4]   2> 1724866 T6807 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 1724866 T6807 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 1724866 T6807 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.ChaosMonkeySafeLeaderTest-96E3644E33657736-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=fr_LU, timezone=Africa/Bissau
   [junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_20-ea (64-bit)/cpus=2,threads=1,free=236221160,total=519045120
   [junit4]   2> NOTE: All tests run in this JVM: [BadComponentTest, DocValuesMultiTest, HdfsBasicDistributedZk2Test, TestDefaultSimilarityFactory, SolrCoreTest, CoreContainerCoreInitFailuresTest, HighlighterConfigTest, TestFieldCollectionResource, CoreMergeIndexesAdminHandlerTest, TestRecoveryHdfs, TestHighlightDedupGrouping, ShowFileRequestHandlerTest, TestPseudoReturnFields, LoggingHandlerTest, TestFunctionQuery, InfoHandlerTest, RequestHandlersTest, TestCharFilters, TestFreeTextSuggestions, ResponseHeaderTest, TestDocumentBuilder, TestAddFieldRealTimeGet, HdfsCollectionsAPIDistributedZkTest, TestCursorMarkWithoutUniqueKey, TestMiniSolrCloudCluster, SampleTest, TestSolr4Spatial, PreAnalyzedFieldTest, TestCoreDiscovery, ConnectionManagerTest, TestRandomDVFaceting, BlockDirectoryTest, QueryEqualityTest, TestValueSourceCache, TestCoreContainer, PeerSyncTest, MergeStrategyTest, TestSolrCoreProperties, DistributedTermsComponentTest, SolrCoreCheckLockOnStartupTest, TestFileDictionaryLookup, NotRequiredUniqueKeyTest, ShardRoutingCustomTest, TestCloudManagedSchema, SolrPluginUtilsTest, TestSystemIdResolver, DistributedQueryComponentOptimizationTest, SchemaVersionSpecificBehaviorTest, ResourceLoaderTest, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestElisionMultitermQuery, SolrXmlInZkTest, CoreAdminCreateDiscoverTest, XsltUpdateRequestHandlerTest, TestQueryTypes, BasicDistributedZk2Test, TestSuggestSpellingConverter, TestDFRSimilarityFactory, RequiredFieldsTest, TestJmxIntegration, XmlUpdateRequestHandlerTest, ScriptEngineTest, SpellPossibilityIteratorTest, UnloadDistributedZkTest, TestManagedResourceStorage, TestStressUserVersions, SuggesterTest, TestStressRecovery, AssignTest, HdfsLockFactoryTest, AlternateDirectoryTest, TestDistributedMissingSort, TermVectorComponentDistributedTest, SpatialFilterTest, TestFiltering, TestIndexSearcher, UpdateParamsTest, DisMaxRequestHandlerTest, TestAtomicUpdateErrorCases, BasicZkTest, CurrencyFieldXmlFileTest, DirectSolrSpellCheckerTest, IndexBasedSpellCheckerTest, DateMathParserTest, DOMUtilTest, DocValuesMissingTest, HdfsChaosMonkeySafeLeaderTest, TestMergePolicyConfig, TestExpandComponent, CollectionsAPIDistributedZkTest, ConvertedLegacyTest, TestFastWriter, FieldAnalysisRequestHandlerTest, StatelessScriptUpdateProcessorFactoryTest, AddSchemaFieldsUpdateProcessorFactoryTest, OverseerStatusTest, TestCollectionAPI, MinimalSchemaTest, DistributedDebugComponentTest, MBeansHandlerTest, SoftAutoCommitTest, TestSearchPerf, FullSolrCloudDistribCmdsTest, ShardSplitTest, TestDefaultSearchFieldResource, TestTrie, TestSchemaSimilarityResource, OverseerCollectionProcessorTest, ZkControllerTest, TestCollapseQParserPlugin, TestImplicitCoreProperties, DistribDocExpirationUpdateProcessorTest, SolrIndexSplitterTest, PrimUtilsTest, TestBinaryField, IndexSchemaTest, TestStressVersions, IndexSchemaRuntimeFieldTest, PolyFieldTest, PluginInfoTest, AnalysisAfterCoreReloadTest, BlockCacheTest, PrimitiveFieldTypeTest, HdfsBasicDistributedZkTest, TestReplicationHandler, UniqFieldsUpdateProcessorFactoryTest, TestGroupingSearch, CurrencyFieldOpenExchangeTest, TestMaxScoreQueryParser, TestSchemaResource, SpellCheckCollatorTest, MoreLikeThisHandlerTest, QueryParsingTest, TestQueryUtils, DateFieldTest, RAMDirectoryFactoryTest, SearchHandlerTest, DistributedQueryComponentCustomSortTest, DistributedQueryElevationComponentTest, SliceStateUpdateTest, TestStandardQParsers, TestRequestStatusCollectionAPI, AnalysisErrorHandlingTest, DirectUpdateHandlerTest, HighlighterTest, AliasIntegrationTest, DirectSolrConnectionTest, MultiTermTest, LeaderElectionTest, SuggesterFSTTest, LeaderElectionIntegrationTest, TestOverriddenPrefixQueryForCustomFieldType, OverseerRolesTest, TestNoOpRegenerator, TestDocSet, TestClassNameShortening, ClusterStateUpdateTest, TestJmxMonitoredMap, CircularListTest, OpenExchangeRatesOrgProviderTest, TestLRUCache, TestJoin, TestRandomMergePolicy, TestWriterPerf, TestManagedSchemaFieldResource, TestManagedSchema, ZkNodePropsTest, StressHdfsTest, FastVectorHighlighterTest, UUIDFieldTest, TestExceedMaxTermLength, TestSchemaNameResource, TestRTGBase, CSVRequestHandlerTest, SimpleFacetsTest, ChangedSchemaMergeTest, SimplePostToolTest, TestSort, TestUpdate, BJQParserTest, TestManagedStopFilterFactory, CoreAdminRequestStatusTest, TestSolrXmlPersistor, DocValuesTest, SynonymTokenizerTest, RegexBoostProcessorTest, SuggesterTSTTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest]
   [junit4] Completed in 160.41s, 1 test, 1 failure <<< FAILURES!

[...truncated 605 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1297: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:921: There were test failures: 393 suites, 1637 tests, 1 failure, 42 ignored (21 assumptions)

Total time: 92 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0_20-ea-b11 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message