You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/11/25 04:36:11 UTC
[JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_45) - Build #
3426 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3426/
Java: 32bit/jdk1.6.0_45 -server -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.testDistribSearch
Error Message:
document count mismatch. control=249 sum(shards)=250 cloudClient=250
Stack Trace:
java.lang.AssertionError: document count mismatch. control=249 sum(shards)=250 cloudClient=250
at __randomizedtesting.SeedInfo.seed([E8D63581F0292648:6930BB9987764674]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1238)
at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:195)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.GeneratedMethodAccessor66.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:662)
Build Log:
[...truncated 10178 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
[junit4] 2> 2904440 T7328 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 2904446 T7328 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-ChaosMonkeyNothingIsSafeTest-1385349816460
[junit4] 2> 2904450 T7328 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2904450 T7329 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2904550 T7328 oasc.ZkTestServer.run start zk server on port:50816
[junit4] 2> 2904552 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2904560 T7335 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@181fd5 name:ZooKeeperConnection Watcher:127.0.0.1:50816 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2904561 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2904561 T7328 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2904569 T7330 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1428d48a0950000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 2904570 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2904574 T7337 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@95ab3b name:ZooKeeperConnection Watcher:127.0.0.1:50816/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2904575 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2904575 T7328 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2904582 T7328 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2904587 T7328 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2904592 T7328 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2904599 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2904600 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2904611 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2904612 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2904619 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2904620 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2904627 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2904628 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2904635 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2904635 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2904643 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2904644 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2904650 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2904651 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2904658 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2904658 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2904666 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2904667 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2904674 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2904675 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2904681 T7328 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2904682 T7328 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2905152 T7328 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2905156 T7328 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50823
[junit4] 2> 2905157 T7328 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2905157 T7328 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2905158 T7328 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703
[junit4] 2> 2905158 T7328 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703\'
[junit4] 2> 2905193 T7328 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\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703\solr.xml
[junit4] 2> 2905274 T7328 oasc.CoreContainer.<init> New CoreContainer 25471464
[junit4] 2> 2905274 T7328 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703\]
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2905278 T7328 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2905288 T7328 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2905290 T7328 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2905290 T7328 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2905290 T7328 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50816/solr
[junit4] 2> 2905290 T7328 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2905293 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2905300 T7348 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@aed5b0 name:ZooKeeperConnection Watcher:127.0.0.1:50816 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2905300 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2905303 T7330 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1428d48a0950002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 2905305 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2905308 T7350 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10dac89 name:ZooKeeperConnection Watcher:127.0.0.1:50816/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2905309 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2905312 T7328 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2905320 T7328 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2905329 T7328 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2905335 T7328 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50823_
[junit4] 2> 2905337 T7328 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50823_
[junit4] 2> 2905345 T7328 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2905359 T7328 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2905366 T7328 oasc.Overseer.start Overseer (id=90790285572112387-127.0.0.1:50823_-n_0000000000) starting
[junit4] 2> 2905375 T7328 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2905385 T7352 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2905386 T7328 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2905396 T7328 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2905401 T7328 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2905414 T7351 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2905417 T7353 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2905417 T7353 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2905421 T7353 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2905424 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2905425 T7351 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50823",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50823_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2905425 T7351 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 2905425 T7351 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2905431 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2905434 T7350 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> 2906421 T7353 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2906421 T7353 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703\collection1
[junit4] 2> 2906421 T7353 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 2906424 T7353 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2906424 T7353 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 2906425 T7353 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703\collection1\'
[junit4] 2> 2906425 T7353 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703/collection1/lib/classes/' to classloader
[junit4] 2> 2906427 T7353 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703/collection1/lib/README' to classloader
[junit4] 2> 2906488 T7353 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2906562 T7353 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2906568 T7353 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2906577 T7353 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2907292 T7353 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2907294 T7353 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2907296 T7353 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2907304 T7353 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2907310 T7353 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2907338 T7353 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2907343 T7353 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2907349 T7353 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2907352 T7353 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2907352 T7353 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2907352 T7353 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2907355 T7353 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2907355 T7353 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2907355 T7353 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2907356 T7353 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385349816703\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457/control/data\
[junit4] 2> 2907356 T7353 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abf59
[junit4] 2> 2907356 T7353 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\control\data
[junit4] 2> 2907356 T7353 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457/control/data\index/
[junit4] 2> 2907358 T7353 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\control\data\index' doesn't exist. Creating new index...
[junit4] 2> 2907358 T7353 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\control\data\index
[junit4] 2> 2907359 T7353 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=4.2685546875, floorSegmentMB=2.1103515625, forceMergeDeletesPctAllowed=28.34504110442253, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2907365 T7353 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\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2907365 T7353 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2907369 T7353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2907369 T7353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2907370 T7353 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2907370 T7353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2907370 T7353 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2907370 T7353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2907371 T7353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2907371 T7353 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2907371 T7353 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2907372 T7353 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2907372 T7353 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2907372 T7353 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2907372 T7353 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2907374 T7353 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2907374 T7353 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2907374 T7353 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2907381 T7353 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2907384 T7353 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2907384 T7353 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2907384 T7353 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=11.88671875, floorSegmentMB=0.9892578125, forceMergeDeletesPctAllowed=10.119427884573904, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.29359672598465353
[junit4] 2> 2907384 T7353 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\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2907384 T7353 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2907387 T7353 oass.SolrIndexSearcher.<init> Opening Searcher@174a7ff main
[junit4] 2> 2907389 T7354 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@174a7ff main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2907391 T7353 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2907391 T7353 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50823 collection:control_collection shard:shard1
[junit4] 2> 2907393 T7353 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2907410 T7353 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2907414 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2907417 T7353 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2907417 T7353 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C6124 name=collection1 org.apache.solr.core.SolrCore@1180b5d url=http://127.0.0.1:50823/collection1 node=127.0.0.1:50823_ C6124_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:50823, core=collection1, node_name=127.0.0.1:50823_}
[junit4] 2> 2907417 T7353 C6124 P50823 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50823/collection1/
[junit4] 2> 2907418 T7353 C6124 P50823 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2907418 T7353 C6124 P50823 oasc.SyncStrategy.syncToMe http://127.0.0.1:50823/collection1/ has no replicas
[junit4] 2> 2907418 T7353 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50823/collection1/ shard1
[junit4] 2> 2907418 T7353 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 2907423 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2907433 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2907436 T7350 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> 2907450 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2907456 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2907561 T7350 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> 2907595 T7353 oasc.ZkController.register We are http://127.0.0.1:50823/collection1/ and leader is http://127.0.0.1:50823/collection1/
[junit4] 2> 2907595 T7353 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50823
[junit4] 2> 2907595 T7353 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2907595 T7353 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2907595 T7353 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2907599 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2907599 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2907599 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2907600 T7353 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2907603 T7328 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2907603 T7328 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2907605 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2907606 T7351 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:50823",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50823_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2907607 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2907610 T7357 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2ace63 name:ZooKeeperConnection Watcher:127.0.0.1:50816/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2907611 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2907616 T7328 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2907617 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2907624 T7328 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2907725 T7357 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> 2907727 T7350 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> 2908071 T7328 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2908088 T7328 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50835
[junit4] 2> 2908088 T7328 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2908088 T7328 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2908088 T7328 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635
[junit4] 2> 2908090 T7328 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635\'
[junit4] 2> 2908117 T7328 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635\solr.xml
[junit4] 2> 2908187 T7328 oasc.CoreContainer.<init> New CoreContainer 6849528
[junit4] 2> 2908190 T7328 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635\]
[junit4] 2> 2908191 T7328 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2908191 T7328 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2908191 T7328 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2908191 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2908191 T7328 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2908191 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2908194 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2908194 T7328 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2908194 T7328 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2908200 T7328 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2908200 T7328 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2908203 T7328 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2908203 T7328 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50816/solr
[junit4] 2> 2908203 T7328 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2908205 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2908212 T7368 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b04d90 name:ZooKeeperConnection Watcher:127.0.0.1:50816 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2908212 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2908216 T7330 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1428d48a0950005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 2908217 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2908219 T7370 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@123da30 name:ZooKeeperConnection Watcher:127.0.0.1:50816/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2908220 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2908232 T7328 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2909239 T7328 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50835_
[junit4] 2> 2909242 T7328 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50835_
[junit4] 2> 2909249 T7350 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2909249 T7357 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2909250 T7370 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2909264 T7371 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2909264 T7371 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2909266 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2909266 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2909267 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2909267 T7371 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2909270 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2909271 T7351 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50835",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50835_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2909271 T7351 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 2909272 T7351 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2909277 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2909279 T7370 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> 2909279 T7350 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> 2909280 T7357 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> 2910267 T7371 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2910267 T7371 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635\collection1
[junit4] 2> 2910267 T7371 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2910269 T7371 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2910269 T7371 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2910272 T7371 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635\collection1\'
[junit4] 2> 2910272 T7371 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635/collection1/lib/classes/' to classloader
[junit4] 2> 2910274 T7371 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635/collection1/lib/README' to classloader
[junit4] 2> 2910358 T7371 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2910426 T7371 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2910429 T7371 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2910435 T7371 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2911160 T7371 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2911161 T7371 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2911162 T7371 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2911174 T7371 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2911178 T7371 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2911210 T7371 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2911215 T7371 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2911220 T7371 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2911225 T7371 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2911225 T7371 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2911225 T7371 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2911228 T7371 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2911228 T7371 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2911228 T7371 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2911229 T7371 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385349819635\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457/jetty1\
[junit4] 2> 2911229 T7371 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abf59
[junit4] 2> 2911229 T7371 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty1
[junit4] 2> 2911229 T7371 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457/jetty1\index/
[junit4] 2> 2911229 T7371 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty1\index' doesn't exist. Creating new index...
[junit4] 2> 2911232 T7371 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty1\index
[junit4] 2> 2911232 T7371 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=4.2685546875, floorSegmentMB=2.1103515625, forceMergeDeletesPctAllowed=28.34504110442253, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2911238 T7371 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\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 2911238 T7371 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2911242 T7371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2911243 T7371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2911243 T7371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2911243 T7371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2911243 T7371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2911243 T7371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2911245 T7371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2911245 T7371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2911245 T7371 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2911246 T7371 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2911246 T7371 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2911246 T7371 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2911246 T7371 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2911259 T7371 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2911259 T7371 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2911260 T7371 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2911267 T7371 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2911272 T7371 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2911272 T7371 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2911272 T7371 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=11.88671875, floorSegmentMB=0.9892578125, forceMergeDeletesPctAllowed=10.119427884573904, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.29359672598465353
[junit4] 2> 2911273 T7371 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\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 2911273 T7371 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2911273 T7371 oass.SolrIndexSearcher.<init> Opening Searcher@34a8be main
[junit4] 2> 2911281 T7372 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34a8be main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2911283 T7371 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2911283 T7371 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50835 collection:collection1 shard:shard1
[junit4] 2> 2911285 T7371 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 2911300 T7371 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2911306 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2911308 T7371 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2911308 T7371 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C6125 name=collection1 org.apache.solr.core.SolrCore@11552bb url=http://127.0.0.1:50835/collection1 node=127.0.0.1:50835_ C6125_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:50835, core=collection1, node_name=127.0.0.1:50835_}
[junit4] 2> 2911308 T7371 C6125 P50835 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50835/collection1/
[junit4] 2> 2911310 T7371 C6125 P50835 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2911310 T7371 C6125 P50835 oasc.SyncStrategy.syncToMe http://127.0.0.1:50835/collection1/ has no replicas
[junit4] 2> 2911310 T7371 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50835/collection1/ shard1
[junit4] 2> 2911310 T7371 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 2911312 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2911324 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2911327 T7370 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> 2911328 T7350 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> 2911328 T7357 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> 2911344 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2911352 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2911458 T7357 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> 2911459 T7350 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> 2911459 T7370 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> 2911488 T7371 oasc.ZkController.register We are http://127.0.0.1:50835/collection1/ and leader is http://127.0.0.1:50835/collection1/
[junit4] 2> 2911488 T7371 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50835
[junit4] 2> 2911488 T7371 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2911488 T7371 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2911488 T7371 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2911491 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2911492 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2911492 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2911494 T7371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2911497 T7328 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2911498 T7328 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2911498 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2911500 T7351 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:50835",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50835_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2911508 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2911615 T7370 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> 2911615 T7350 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> 2911616 T7357 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> 2911959 T7328 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2911962 T7328 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50844
[junit4] 2> 2911963 T7328 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2911963 T7328 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2911963 T7328 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511
[junit4] 2> 2911963 T7328 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511\'
[junit4] 2> 2911992 T7328 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\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511\solr.xml
[junit4] 2> 2912062 T7328 oasc.CoreContainer.<init> New CoreContainer 29348260
[junit4] 2> 2912064 T7328 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511\]
[junit4] 2> 2912066 T7328 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2912066 T7328 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2912066 T7328 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2912066 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2912066 T7328 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2912066 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2912069 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2912069 T7328 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2912069 T7328 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2912075 T7328 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2912075 T7328 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2912075 T7328 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2912075 T7328 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50816/solr
[junit4] 2> 2912077 T7328 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2912080 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2912090 T7384 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5c7123 name:ZooKeeperConnection Watcher:127.0.0.1:50816 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2912090 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2912094 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2912097 T7386 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@232cf6 name:ZooKeeperConnection Watcher:127.0.0.1:50816/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2912098 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2912107 T7328 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2913116 T7328 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50844_
[junit4] 2> 2913118 T7328 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50844_
[junit4] 2> 2913125 T7350 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2913126 T7370 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2913128 T7386 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2913128 T7357 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2913142 T7387 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2913142 T7387 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2913144 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2913144 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2913145 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2913145 T7387 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2913148 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2913150 T7351 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50844",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50844_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2913150 T7351 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2913150 T7351 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 2913156 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2913158 T7386 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> 2913158 T7350 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> 2913159 T7357 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> 2913159 T7370 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> 2914208 T7387 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2914208 T7387 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511\collection1
[junit4] 2> 2914208 T7387 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2914210 T7387 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2914210 T7387 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2914213 T7387 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511\collection1\'
[junit4] 2> 2914213 T7387 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511/collection1/lib/classes/' to classloader
[junit4] 2> 2914216 T7387 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511/collection1/lib/README' to classloader
[junit4] 2> 2914303 T7387 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 2914375 T7387 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2914380 T7387 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2914386 T7387 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2915191 T7387 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2915192 T7387 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2915192 T7387 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2915205 T7387 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2915210 T7387 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2915243 T7387 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2915250 T7387 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2915258 T7387 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2915261 T7387 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2915261 T7387 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2915261 T7387 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2915266 T7387 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2915266 T7387 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2915266 T7387 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2915267 T7387 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385349823511\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457/jetty2\
[junit4] 2> 2915267 T7387 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abf59
[junit4] 2> 2915267 T7387 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty2
[junit4] 2> 2915267 T7387 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457/jetty2\index/
[junit4] 2> 2915270 T7387 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty2\index' doesn't exist. Creating new index...
[junit4] 2> 2915270 T7387 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty2\index
[junit4] 2> 2915271 T7387 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=4.2685546875, floorSegmentMB=2.1103515625, forceMergeDeletesPctAllowed=28.34504110442253, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2915277 T7387 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\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 2915277 T7387 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2915282 T7387 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2915282 T7387 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2915282 T7387 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2915282 T7387 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2915282 T7387 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2915282 T7387 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2915284 T7387 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2915284 T7387 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2915284 T7387 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2915285 T7387 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2915286 T7387 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2915286 T7387 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2915286 T7387 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2915287 T7387 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2915287 T7387 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2915288 T7387 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2915293 T7387 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2915300 T7387 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2915300 T7387 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2915300 T7387 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=11.88671875, floorSegmentMB=0.9892578125, forceMergeDeletesPctAllowed=10.119427884573904, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.29359672598465353
[junit4] 2> 2915300 T7387 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\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385349816457\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 2915300 T7387 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2915300 T7387 oass.SolrIndexSearcher.<init> Opening Searcher@a14d9b main
[junit4] 2> 2915307 T7388 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a14d9b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2915309 T7387 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2915309 T7387 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50844 collection:collection1 shard:shard2
[junit4] 2> 2915311 T7387 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 2915331 T7387 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 2915337 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2915340 T7387 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2915340 T7387 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C6126 name=collection1 org.apache.solr.core.SolrCore@bfedc4 url=http://127.0.0.1:50844/collection1 node=127.0.0.1:50844_ C6126_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:50844, core=collection1, node_name=127.0.0.1:50844_}
[junit4] 2> 2915340 T7387 C6126 P50844 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50844/collection1/
[junit4] 2> 2915341 T7387 C6126 P50844 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2915341 T7387 C6126 P50844 oasc.SyncStrategy.syncToMe http://127.0.0.1:50844/collection1/ has no replicas
[junit4] 2> 2915341 T7387 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50844/collection1/ shard2
[junit4] 2> 2915343 T7387 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 2915346 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2915362 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2915367 T7386 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> 2915368 T7370 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> 2915371 T7350 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> 2915374 T7357 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> 2915390 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2915399 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2915516 T7386 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> 2915516 T7350 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> 2915517 T7357 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> 2915517 T7370 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> 2915547 T7387 oasc.ZkController.register We are http://127.0.0.1:50844/collection1/ and leader is http://127.0.0.1:50844/collection1/
[junit4] 2> 2915547 T7387 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50844
[junit4] 2> 2915547 T7387 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2915547 T7387 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2915547 T7387 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2915552 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2915552 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2915552 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2915554 T7387 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2915558 T7328 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2915559 T7328 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2915560 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2915562 T7351 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:50844",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50844_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 2915575 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2915698 T7357 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> 2915700 T7386 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> 2915701 T7370 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> 2915702 T7350 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> 2916109 T7328 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2916117 T7328 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50853
[junit4] 2> 2916118 T7328 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2916118 T7328 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2916118 T7328 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385349827572
[junit4] 2> 2916118 T7328 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385349827572\'
[junit4] 2> 2916151 T7328 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385349827572\solr.xml
[junit4] 2> 2916250 T7328 oasc.CoreContainer.<init> New CoreContainer 6822410
[junit4] 2> 2916251 T7328 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385349827572\]
[junit4] 2> 2916253 T7328 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2916253 T7328 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2916254 T7328 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2916254 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2916254 T7328 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2916255 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2916255 T7328 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2916255 T7328 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2916256 T7328 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2916266 T7328 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2916266 T7328 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2916267 T7328 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2916267 T7328 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50816/solr
[junit4] 2> 2916267 T7328 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2916269 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2916276 T7400 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@85a47b name:ZooKeeperConnection Watcher:127.0.0.1:50816 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2916277 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2916280 T7330 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1428d48a0950009, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 2916283 T7328 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2916286 T7402 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1318f4f name:ZooKeeperConnection Watcher:127.0.0.1:50816/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2916287 T7328 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2916298 T7328 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2917407 T7328 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50853_
[junit4] 2> 2917410 T7328 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50853_
[junit4] 2> 2917418 T7357 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2917420 T7350 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2917421 T7370 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2917422 T7386 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2917423 T7402 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2917439 T7403 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2917439 T7403 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2917441 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2917441 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2917441 T7350 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2917442 T7403 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2917445 T7351 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2917447 T7351 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50853",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50853_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2917447 T7351 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards
[...truncated too long message...]
0.0.1:50900_-n_0000000007 (0)
[junit4] 1> /solr/overseer_elect/election/90790285572112400-127.0.0.1:50887_-n_0000000006 (0)
[junit4] 1> /solr/overseer_elect/election/90790285572112394-127.0.0.1:50853_-n_0000000003 (0)
[junit4] 1> /solr/overseer_elect/election/90790285572112404-127.0.0.1:50844_-n_0000000008 (0)
[junit4] 1> /solr/overseer_elect/election/90790285572112387-127.0.0.1:50823_-n_0000000000 (0)
[junit4] 1> /solr/overseer_elect/leader (0)
[junit4] 1> DATA:
[junit4] 1> {"id":"90790285572112387-127.0.0.1:50823_-n_0000000000"}
[junit4] 1> /zookeeper (1)
[junit4] 1> DATA:
[junit4] 1>
[junit4] 1>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ChaosMonkeyNothingIsSafeTest -Dtests.method=testDistribSearch -Dtests.seed=E8D63581F0292648 -Dtests.slow=true -Dtests.locale=en_IN -Dtests.timezone=Europe/Malta -Dtests.file.encoding=UTF-8
[junit4] FAILURE 94.1s | ChaosMonkeyNothingIsSafeTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: document count mismatch. control=249 sum(shards)=250 cloudClient=250
[junit4] > at __randomizedtesting.SeedInfo.seed([E8D63581F0292648:6930BB9987764674]:0)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1238)
[junit4] > at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:195)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:662)
[junit4] 2> 2998522 T7328 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=en_IN, timezone=Europe/Malta
[junit4] 2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_45 (32-bit)/cpus=2,threads=1,free=88206328,total=196964352
[junit4] 2> NOTE: All tests run in this JVM: [AutoCommitTest, TestInfoStreamLogging, TestCollationField, AlternateDirectoryTest, UpdateParamsTest, TestReload, TestSolrCoreProperties, TestSort, ZkNodePropsTest, SoftAutoCommitTest, SimplePostToolTest, TermVectorComponentDistributedTest, TestRealTimeGet, TestElisionMultitermQuery, TestReversedWildcardFilterFactory, MoreLikeThisHandlerTest, TestExtendedDismaxParser, SolrIndexConfigTest, TestIndexingPerformance, TestRandomDVFaceting, ClusterStateTest, TestSolrXml, ShardSplitTest, SpatialFilterTest, TestHighFrequencyDictionaryFactory, UUIDFieldTest, TestShardHandlerFactory, UniqFieldsUpdateProcessorFactoryTest, DateMathParserTest, TestValueSourceCache, TestFieldCollectionResource, NoCacheHeaderTest, CurrencyFieldOpenExchangeTest, SpellCheckComponentTest, SignatureUpdateProcessorFactoryTest, ShardRoutingCustomTest, OpenCloseCoreStressTest, SyncSliceTest, DateFieldTest, TestCollationKeyRangeQueries, OpenExchangeRatesOrgProviderTest, TestUtils, SynonymTokenizerTest, BlockCacheTest, TestRecovery, TestPHPSerializedResponseWriter, InfoHandlerTest, TestLazyCores, DocValuesTest, CurrencyFieldXmlFileTest, TestStressUserVersions, BadIndexSchemaTest, DistributedSuggesterComponentTest, TestLFUCache, TestArbitraryIndexDir, TestRandomMergePolicy, TestSearchPerf, TestWriterPerf, TestSystemIdResolver, TestBM25SimilarityFactory, TestNoOpRegenerator, TestAnalyzeInfixSuggestions, TestCSVResponseWriter, HdfsBasicDistributedZk2Test, TestGroupingSearch, AddSchemaFieldsUpdateProcessorFactoryTest, RequestHandlersTest, TestFileDictionaryLookup, OutputWriterTest, TestTrie, TestCharFilters, ZkSolrClientTest, ConvertedLegacyTest, DirectUpdateHandlerTest, TestDocumentBuilder, TestSolrQueryParser, TestQueryUtils, SolrTestCaseJ4Test, TestStressReorder, TestNRTOpen, WordBreakSolrSpellCheckerTest, TestComponentsName, StressHdfsTest, ScriptEngineTest, FastVectorHighlighterTest, TestSuggestSpellingConverter, SuggestComponentTest, SystemInfoHandlerTest, TestSolr4Spatial, QueryResultKeyTest, TestQuerySenderNoQuery, TestAddFieldRealTimeGet, ShardRoutingTest, TestJmxIntegration, TermVectorComponentTest, TestSolrQueryParserDefaultOperatorResource, AddBlockUpdateTest, HdfsDirectoryTest, SpellPossibilityIteratorTest, BasicFunctionalityTest, HardAutoCommitTest, TestReplicationHandler, DirectSolrSpellCheckerTest, ZkControllerTest, TestDistributedGrouping, DisMaxRequestHandlerTest, HdfsUnloadDistributedZkTest, RequiredFieldsTest, FullSolrCloudDistribCmdsTest, TestMaxScoreQueryParser, SliceStateTest, TestFieldTypeCollectionResource, TermsComponentTest, TestSolrXmlPersistor, ClusterStateUpdateTest, MultiTermTest, TestSurroundQueryParser, SolrIndexSplitterTest, TestCloudManagedSchemaAddField, MBeansHandlerTest, TestDefaultSimilarityFactory, PreAnalyzedUpdateProcessorTest, TestBinaryField, EchoParamsTest, TestDFRSimilarityFactory, TestCoreDiscovery, TestCSVLoader, TestSolrDeletionPolicy2, UnloadDistributedZkTest, StatelessScriptUpdateProcessorFactoryTest, TestNumberUtils, DOMUtilTest, LeaderElectionTest, OverseerCollectionProcessorTest, TestSchemaSimilarityResource, TestSolrJ, HdfsChaosMonkeySafeLeaderTest, RAMDirectoryFactoryTest, JSONWriterTest, LoggingHandlerTest, TestRecoveryHdfs, PluginInfoTest, TestRemoteStreaming, HdfsLockFactoryTest, CollectionsAPIDistributedZkTest, CoreAdminCreateDiscoverTest, TestXIncludeConfig, TestQuerySenderListener, DistributedSpellCheckComponentTest, TestDynamicFieldCollectionResource, FieldMutatingUpdateProcessorTest, ExternalFileFieldSortTest, ResourceLoaderTest, TestDocSet, TestReloadAndDeleteDocs, TestMultiCoreConfBootstrap, OverseerTest, DeleteInactiveReplicaTest, TestMergePolicyConfig, CachingDirectoryFactoryTest, TestStressRecovery, UpdateRequestProcessorFactoryTest, TestSchemaVersionResource, BasicZkTest, ResponseLogComponentTest, TestPostingsSolrHighlighter, LukeRequestHandlerTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, LeaderElectionIntegrationTest, RecoveryZkTest, TestRandomFaceting, TestZkChroot, ZkCLITest, SliceStateUpdateTest, TestDistributedSearch, TestFaceting, TestHashPartitioner, TestJoin, TestStressVersions, DistributedTermsComponentTest, TestRangeQuery, TestCoreContainer, SimpleFacetsTest, SolrCoreTest, StatsComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, PeerSyncTest, TestFiltering, TestFunctionQuery, TestBadConfig, TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, AnalysisAfterCoreReloadTest, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, SolrRequestParserTest, TestFoldingMultitermQuery, SuggesterTSTTest, SuggesterTest, TestStressLucene, SuggesterWFSTTest, PolyFieldTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestUpdate, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, SortByFunctionTest, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, StandardRequestHandlerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, ReturnFieldsTest, QueryParsingTest, TestAnalyzedSuggestions, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestBinaryResponseWriter, HighlighterConfigTest, SOLR749Test, TestSolrIndexConfig, CopyFieldTest, BadComponentTest, SampleTest, NumericFieldsTest, MinimalSchemaTest, TestConfig, TestFuzzyAnalyzedSuggestions, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, TestCodecSupport, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, LegacyHTMLStripCharFilterTest, TestJmxMonitoredMap, TimeZoneUtilsTest, TestFastOutputStream, URLClassifyProcessorTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest]
[junit4] Completed in 94.11s, 1 test, 1 failure <<< FAILURES!
[...truncated 194 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:426: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:406: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: 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:492: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1279: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:912: There were test failures: 345 suites, 1505 tests, 1 failure, 41 ignored (13 assumptions)
Total time: 88 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.6.0_45 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure