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/21 03:42:27 UTC
[JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.8.0-ea-b114) - Build
# 3411 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3411/
Java: 32bit/jdk1.8.0-ea-b114 -server -XX:+UseSerialGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.testDistribSearch
Error Message:
expected:<0> but was:<1>
Stack Trace:
java.lang.AssertionError: expected:<0> but was:<1>
at __randomizedtesting.SeedInfo.seed([83E960FB7053D2CA:20FEEE3070CB2F6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:169)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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:744)
REGRESSION: org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT
Error Message:
expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([83E960FB7053D2CA:366F017CCF92603E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.core.TestNonNRTOpen.assertNotNRT(TestNonNRTOpen.java:133)
at org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT(TestNonNRTOpen.java:94)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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:744)
Build Log:
[...truncated 10230 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
[junit4] 2> 280920 T657 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /vr/w
[junit4] 2> 280927 T657 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-1384999736799
[junit4] 2> 280930 T657 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 280934 T658 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 281039 T657 oasc.ZkTestServer.run start zk server on port:54098
[junit4] 2> 281041 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 281058 T664 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1aa87a8 name:ZooKeeperConnection Watcher:127.0.0.1:54098 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 281058 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 281058 T657 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 281066 T659 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x142786ad5f20000, 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:744)
[junit4] 2>
[junit4] 2> 281069 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 281074 T666 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@eb3cc2 name:ZooKeeperConnection Watcher:127.0.0.1:54098/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 281074 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 281074 T657 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 281080 T657 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 281084 T657 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 281088 T657 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 281093 T657 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> 281093 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 281099 T657 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> 281099 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 281105 T657 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> 281105 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 281121 T657 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> 281121 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 281127 T657 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> 281127 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 281132 T657 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> 281132 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 281137 T657 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> 281137 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 281141 T657 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> 281141 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 281146 T657 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> 281146 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 281154 T657 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> 281154 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 281159 T657 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> 281159 T657 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 281164 T659 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x142786ad5f20001, 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:744)
[junit4] 2>
[junit4] 2> 281696 T657 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 281701 T657 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54105
[junit4] 2> 281701 T657 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 281701 T657 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 281701 T657 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1384999737034
[junit4] 2> 281702 T657 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1384999737034\'
[junit4] 2> 281736 T657 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-controljetty-1384999737034\solr.xml
[junit4] 2> 281764 T657 oasc.CoreContainer.<init> New CoreContainer 32133938
[junit4] 2> 281764 T657 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1384999737034\]
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 281766 T657 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 281772 T657 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 281772 T657 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 281774 T657 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 281774 T657 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54098/solr
[junit4] 2> 281774 T657 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 281776 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 281786 T677 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18a1232 name:ZooKeeperConnection Watcher:127.0.0.1:54098 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 281786 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 281797 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 281800 T679 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@197d613 name:ZooKeeperConnection Watcher:127.0.0.1:54098/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 281801 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 281804 T657 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 281812 T657 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 281816 T657 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 281819 T657 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54105_vr%2Fw
[junit4] 2> 281820 T657 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54105_vr%2Fw
[junit4] 2> 281825 T657 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 281833 T657 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 281836 T657 oasc.Overseer.start Overseer (id=90767342752104451-127.0.0.1:54105_vr%2Fw-n_0000000000) starting
[junit4] 2> 281841 T657 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 281851 T681 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 281852 T657 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 281856 T657 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 281859 T657 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 281863 T680 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 281868 T682 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 281868 T682 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 281870 T682 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 283492 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 283494 T680 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:54105/vr/w",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54105_vr%2Fw",
[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> 283494 T680 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 283495 T680 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 283511 T679 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> 284027 T682 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 284027 T682 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1384999737034\collection1
[junit4] 2> 284027 T682 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 284030 T682 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 284030 T682 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 284033 T682 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1384999737034\collection1\'
[junit4] 2> 284033 T682 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-1384999737034/collection1/lib/classes/' to classloader
[junit4] 2> 284037 T682 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-1384999737034/collection1/lib/README' to classloader
[junit4] 2> 284083 T682 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 284099 T682 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 284104 T682 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 284108 T682 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 284250 T682 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 284250 T682 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 284251 T682 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 284258 T682 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 284259 T682 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 284283 T682 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 284287 T682 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 284290 T682 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 284292 T682 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 284292 T682 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 284292 T682 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 284294 T682 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 284294 T682 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 284294 T682 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 284330 T682 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1384999737034\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797/control/data\
[junit4] 2> 284330 T682 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c46f91
[junit4] 2> 284333 T682 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-1384999736797\control\data
[junit4] 2> 284334 T682 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797/control/data\index/
[junit4] 2> 284335 T682 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797\control\data\index' doesn't exist. Creating new index...
[junit4] 2> 284335 T682 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-1384999736797\control\data\index
[junit4] 2> 284336 T682 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=86.650390625, floorSegmentMB=0.6376953125, forceMergeDeletesPctAllowed=9.326258081473718, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6906355897061915
[junit4] 2> 284344 T682 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-1384999736797\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 284344 T682 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 284346 T682 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 284347 T682 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 284347 T682 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 284347 T682 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 284347 T682 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 284347 T682 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 284348 T682 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 284348 T682 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 284348 T682 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 284348 T682 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 284349 T682 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 284349 T682 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 284349 T682 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 284349 T682 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 284350 T682 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 284350 T682 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 284355 T682 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 284358 T682 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 284358 T682 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 284359 T682 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=20.9306640625, floorSegmentMB=1.12109375, forceMergeDeletesPctAllowed=9.495458263115266, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.42165722571182307
[junit4] 2> 284359 T682 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-1384999736797\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 284359 T682 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 284359 T682 oass.SolrIndexSearcher.<init> Opening Searcher@653817 main
[junit4] 2> 284364 T683 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@653817 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 284364 T682 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 284364 T682 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54105/vr/w collection:control_collection shard:shard1
[junit4] 2> 284370 T682 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 284382 T682 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 284386 T682 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 284386 T682 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C707 name=collection1 org.apache.solr.core.SolrCore@1bfa6f url=http://127.0.0.1:54105/vr/w/collection1 node=127.0.0.1:54105_vr%2Fw C707_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:54105/vr/w, core=collection1, node_name=127.0.0.1:54105_vr%2Fw}
[junit4] 2> 284386 T682 C707 P54105 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54105/vr/w/collection1/
[junit4] 2> 284386 T682 C707 P54105 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 284386 T682 C707 P54105 oasc.SyncStrategy.syncToMe http://127.0.0.1:54105/vr/w/collection1/ has no replicas
[junit4] 2> 284386 T682 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54105/vr/w/collection1/ shard1
[junit4] 2> 284386 T682 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 285141 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 285155 T679 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> 285218 T682 oasc.ZkController.register We are http://127.0.0.1:54105/vr/w/collection1/ and leader is http://127.0.0.1:54105/vr/w/collection1/
[junit4] 2> 285218 T682 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54105/vr/w
[junit4] 2> 285218 T682 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 285218 T682 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 285218 T682 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 285225 T682 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 285227 T657 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 285228 T657 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 285235 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 285243 T686 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@498de2 name:ZooKeeperConnection Watcher:127.0.0.1:54098/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 285243 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 285246 T657 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 285252 T657 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 285809 T657 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 285812 T657 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54117
[junit4] 2> 285816 T657 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 285816 T657 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 285816 T657 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-jetty1-1384999741122
[junit4] 2> 285816 T657 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-jetty1-1384999741122\'
[junit4] 2> 285846 T657 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-jetty1-1384999741122\solr.xml
[junit4] 2> 285870 T657 oasc.CoreContainer.<init> New CoreContainer 14839334
[junit4] 2> 285870 T657 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-jetty1-1384999741122\]
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 285872 T657 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 285879 T657 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 285880 T657 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 285880 T657 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 285880 T657 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54098/solr
[junit4] 2> 285880 T657 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 285882 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 285893 T697 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d82d7 name:ZooKeeperConnection Watcher:127.0.0.1:54098 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 285894 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 285905 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 285912 T699 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1089ee1 name:ZooKeeperConnection Watcher:127.0.0.1:54098/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 285913 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 285927 T657 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 286786 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 286788 T680 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:54105/vr/w",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54105_vr%2Fw",
[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> 286803 T679 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> 286803 T699 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> 286804 T686 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> 287015 T657 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54117_vr%2Fw
[junit4] 2> 287018 T657 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54117_vr%2Fw
[junit4] 2> 287026 T679 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 287028 T699 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 287028 T686 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 287045 T700 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 287045 T700 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 287048 T700 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 288435 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 288436 T680 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:54117/vr/w",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54117_vr%2Fw",
[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> 288436 T680 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 288436 T680 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 288446 T679 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> 288446 T699 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> 288447 T686 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> 289218 T700 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 289218 T700 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-jetty1-1384999741122\collection1
[junit4] 2> 289218 T700 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 289219 T700 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 289219 T700 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 289221 T700 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-jetty1-1384999741122\collection1\'
[junit4] 2> 289221 T700 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-1384999741122/collection1/lib/classes/' to classloader
[junit4] 2> 289225 T700 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-1384999741122/collection1/lib/README' to classloader
[junit4] 2> 289256 T700 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 289265 T700 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 289269 T700 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 289274 T700 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 289424 T700 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 289425 T700 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 289425 T700 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 289433 T700 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 289435 T700 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 289455 T700 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 289457 T700 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 289462 T700 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 289463 T700 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 289463 T700 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 289463 T700 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 289465 T700 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 289465 T700 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 289465 T700 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 289465 T700 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-jetty1-1384999741122\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797/jetty1\
[junit4] 2> 289465 T700 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c46f91
[junit4] 2> 289468 T700 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-1384999736797\jetty1
[junit4] 2> 289468 T700 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797/jetty1\index/
[junit4] 2> 289468 T700 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797\jetty1\index' doesn't exist. Creating new index...
[junit4] 2> 289470 T700 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-1384999736797\jetty1\index
[junit4] 2> 289471 T700 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=86.650390625, floorSegmentMB=0.6376953125, forceMergeDeletesPctAllowed=9.326258081473718, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6906355897061915
[junit4] 2> 289476 T700 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-1384999736797\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 289476 T700 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 289478 T700 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 289478 T700 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 289479 T700 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 289479 T700 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 289479 T700 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 289479 T700 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 289481 T700 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 289481 T700 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 289481 T700 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 289481 T700 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 289489 T700 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 289489 T700 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 289489 T700 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 289490 T700 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 289490 T700 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 289490 T700 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 289496 T700 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 289499 T700 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 289499 T700 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 289500 T700 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=20.9306640625, floorSegmentMB=1.12109375, forceMergeDeletesPctAllowed=9.495458263115266, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.42165722571182307
[junit4] 2> 289500 T700 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-1384999736797\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 289500 T700 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 289500 T700 oass.SolrIndexSearcher.<init> Opening Searcher@165ab6c main
[junit4] 2> 289506 T700 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 289506 T700 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54117/vr/w collection:collection1 shard:shard2
[junit4] 2> 289507 T701 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@165ab6c main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 289508 T700 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 289521 T700 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 289526 T700 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 289526 T700 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C708 name=collection1 org.apache.solr.core.SolrCore@1c71a63 url=http://127.0.0.1:54117/vr/w/collection1 node=127.0.0.1:54117_vr%2Fw C708_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:54117/vr/w, core=collection1, node_name=127.0.0.1:54117_vr%2Fw}
[junit4] 2> 289526 T700 C708 P54117 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54117/vr/w/collection1/
[junit4] 2> 289526 T700 C708 P54117 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 289526 T700 C708 P54117 oasc.SyncStrategy.syncToMe http://127.0.0.1:54117/vr/w/collection1/ has no replicas
[junit4] 2> 289527 T700 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54117/vr/w/collection1/ shard2
[junit4] 2> 289527 T700 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 290174 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 290206 T679 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> 290206 T699 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> 290206 T686 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> 290259 T700 oasc.ZkController.register We are http://127.0.0.1:54117/vr/w/collection1/ and leader is http://127.0.0.1:54117/vr/w/collection1/
[junit4] 2> 290259 T700 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54117/vr/w
[junit4] 2> 290259 T700 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 290259 T700 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 290259 T700 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 290265 T700 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 290269 T657 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 290270 T657 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 290896 T657 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 290903 T657 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54126
[junit4] 2> 290904 T657 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 290905 T657 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 290905 T657 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1384999746140
[junit4] 2> 290906 T657 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1384999746140\'
[junit4] 2> 290940 T657 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-jetty2-1384999746140\solr.xml
[junit4] 2> 290951 T657 oasc.CoreContainer.<init> New CoreContainer 24038636
[junit4] 2> 290955 T657 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1384999746140\]
[junit4] 2> 290955 T657 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 290955 T657 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 290955 T657 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 290955 T657 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 290955 T657 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 290955 T657 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 290955 T657 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 290955 T657 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 290957 T657 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 290961 T657 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 290961 T657 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 290961 T657 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 290963 T657 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54098/solr
[junit4] 2> 290963 T657 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 290966 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 290976 T713 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1874ba0 name:ZooKeeperConnection Watcher:127.0.0.1:54098 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 290977 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 290983 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 290986 T715 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d4af76 name:ZooKeeperConnection Watcher:127.0.0.1:54098/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 290987 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 290994 T657 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 292006 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 292009 T680 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:54117/vr/w",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54117_vr%2Fw",
[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_node1"}
[junit4] 2> 292026 T715 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> 292028 T679 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> 292030 T686 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> 292030 T699 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> 292186 T657 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54126_vr%2Fw
[junit4] 2> 292189 T657 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54126_vr%2Fw
[junit4] 2> 292200 T686 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 292200 T715 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 292201 T699 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 292202 T679 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 292224 T716 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 292224 T716 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 292229 T716 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 293822 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 293823 T680 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:54126/vr/w",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54126_vr%2Fw",
[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> 293823 T680 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 293823 T680 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 293833 T715 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> 293833 T679 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> 293834 T699 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> 293833 T686 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> 294600 T716 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 294600 T716 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1384999746140\collection1
[junit4] 2> 294600 T716 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 294604 T716 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 294604 T716 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 294607 T716 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1384999746140\collection1\'
[junit4] 2> 294607 T716 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-1384999746140/collection1/lib/classes/' to classloader
[junit4] 2> 294612 T716 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-1384999746140/collection1/lib/README' to classloader
[junit4] 2> 294671 T716 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 294683 T716 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 294686 T716 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 294691 T716 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 294839 T716 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 294841 T716 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 294841 T716 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 294849 T716 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 294849 T716 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 294871 T716 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 294876 T716 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 294880 T716 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 294882 T716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 294882 T716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 294882 T716 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 294884 T716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 294884 T716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 294884 T716 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 294884 T716 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1384999746140\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797/jetty2\
[junit4] 2> 294884 T716 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c46f91
[junit4] 2> 294884 T716 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-1384999736797\jetty2
[junit4] 2> 294887 T716 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797/jetty2\index/
[junit4] 2> 294887 T716 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1384999736797\jetty2\index' doesn't exist. Creating new index...
[junit4] 2> 294887 T716 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-1384999736797\jetty2\index
[junit4] 2> 294890 T716 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=86.650390625, floorSegmentMB=0.6376953125, forceMergeDeletesPctAllowed=9.326258081473718, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6906355897061915
[junit4] 2> 294896 T716 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-1384999736797\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 294896 T716 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 294898 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 294898 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 294899 T716 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 294899 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 294899 T716 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 294899 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 294900 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 294906 T716 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 294906 T716 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 294907 T716 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 294907 T716 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 294907 T716 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 294907 T716 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 294909 T716 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 294909 T716 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 294909 T716 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 294914 T716 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 294921 T716 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 294921 T716 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 294926 T716 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=20.9306640625, floorSegmentMB=1.12109375, forceMergeDeletesPctAllowed=9.495458263115266, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.42165722571182307
[junit4] 2> 294928 T716 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-1384999736797\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 294928 T716 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 294928 T716 oass.SolrIndexSearcher.<init> Opening Searcher@187691f main
[junit4] 2> 294941 T716 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 294941 T716 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54126/vr/w collection:collection1 shard:shard1
[junit4] 2> 294942 T717 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@187691f main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 294943 T716 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 294954 T716 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 294958 T716 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 294958 T716 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C709 name=collection1 org.apache.solr.core.SolrCore@779eb url=http://127.0.0.1:54126/vr/w/collection1 node=127.0.0.1:54126_vr%2Fw C709_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:54126/vr/w, core=collection1, node_name=127.0.0.1:54126_vr%2Fw}
[junit4] 2> 294958 T716 C709 P54126 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54126/vr/w/collection1/
[junit4] 2> 294958 T716 C709 P54126 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 294958 T716 C709 P54126 oasc.SyncStrategy.syncToMe http://127.0.0.1:54126/vr/w/collection1/ has no replicas
[junit4] 2> 294958 T716 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54126/vr/w/collection1/ shard1
[junit4] 2> 294958 T716 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 295627 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 295658 T715 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> 295658 T686 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> 295658 T699 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> 295658 T679 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> 295691 T716 oasc.ZkController.register We are http://127.0.0.1:54126/vr/w/collection1/ and leader is http://127.0.0.1:54126/vr/w/collection1/
[junit4] 2> 295691 T716 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54126/vr/w
[junit4] 2> 295691 T716 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 295691 T716 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 295691 T716 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 295696 T716 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 295700 T657 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 295701 T657 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 296320 T657 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 296325 T657 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54135
[junit4] 2> 296326 T657 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 296327 T657 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 296327 T657 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-jetty3-1384999751572
[junit4] 2> 296327 T657 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-jetty3-1384999751572\'
[junit4] 2> 296372 T657 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-jetty3-1384999751572\solr.xml
[junit4] 2> 296383 T657 oasc.CoreContainer.<init> New CoreContainer 1189698
[junit4] 2> 296385 T657 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-jetty3-1384999751572\]
[junit4] 2> 296386 T657 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 296386 T657 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 296386 T657 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 296386 T657 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 296386 T657 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 296386 T657 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 296386 T657 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 296386 T657 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 296389 T657 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 296393 T657 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 296393 T657 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 296393 T657 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 296393 T657 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54098/solr
[junit4] 2> 296393 T657 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 296397 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 296404 T729 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12f037e name:ZooKeeperConnection Watcher:127.0.0.1:54098 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 296405 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 296414 T657 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 296416 T731 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e17c name:ZooKeeperConnection Watcher:127.0.0.1:54098/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 296418 T657 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 296425 T657 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 297461 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 297463 T680 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:54126/vr/w",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54126_vr%2Fw",
[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_node2"}
[junit4] 2> 297480 T715 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> 297480 T686 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> 297480 T699 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> 297481 T731 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> 297480 T679 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> 297617 T657 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54135_vr%2Fw
[junit4] 2> 297622 T657 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54135_vr%2Fw
[junit4] 2> 297631 T715 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 297632 T699 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 297634 T679 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 297634 T731 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 297636 T686 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 297657 T732 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 297657 T732 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 297661 T732 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 299282 T680 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 299284 T680 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:54135/vr/w",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54135_vr%2Fw",
[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> 299284 T680 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 299284 T680 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 299294 T715 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 299294 T679 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 299294 T686 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 299294 T731 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 299294 T699 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 300033 T732 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 300033 T732 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-jetty3-1384999751572\collection1
[junit4] 2> 300033 T732 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 300037 T732 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 300037 T732 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 300041 T732 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-jetty3-1384999751572\collection1\'
[junit4] 2> 300045 T732 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-jetty3-1384999751572/collection1/lib/classes/' to classloader
[junit4] 2> 300046 T732 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-jetty3-1384999751572/collection1/lib/README' to classloader
[junit4] 2> 300085 T732 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 300104 T732 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 300111 T732 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 300118 T732 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 300273 T732 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 300274 T732 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 300274 T732 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 300283 T732 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 300283 T732 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 300308 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 300312 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 300315 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 300317 T732 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 300317 T732 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 300317 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3003
[...truncated too long message...]
uest.
[junit4] 2> 2198572 T8160 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2198572 T8160 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1385001653327\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1385001653327\index;done=false>>]
[junit4] 2> 2198572 T8160 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1385001653327\index
[junit4] 2> 2198572 T8160 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1385001653327 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1385001653327;done=false>>]
[junit4] 2> 2198572 T8160 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1385001653327
[junit4] 2> NOTE: test params are: codec=Asserting, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=lv, timezone=America/Paramaribo
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=26643040,total=232181760
[junit4] 2> NOTE: All tests run in this JVM: [DefaultValueUpdateProcessorTest, DateFieldTest, DeleteShardTest, TestQuerySenderListener, TestHashPartitioner, TestImplicitCoreProperties, TestReversedWildcardFilterFactory, BasicDistributedZkTest, TestRandomFaceting, FieldAnalysisRequestHandlerTest, TestDocBasedVersionConstraints, TestReload, TestShardHandlerFactory, ZkCLITest, SolrCmdDistributorTest, DocValuesMultiTest, ChaosMonkeyNothingIsSafeTest, MinimalSchemaTest, QueryResultKeyTest, TestIndexingPerformance, CacheHeaderTest, RequestHandlersTest, TermsComponentTest, TestDefaultSearchFieldResource, MoreLikeThisHandlerTest, NumericFieldsTest, IndexBasedSpellCheckerTest, TestStressLucene, ZkControllerTest, SpellCheckComponentTest, AssignTest, TestPartialUpdateDeduplication, TestDistributedSearch, TestJoin, TestAnalyzeInfixSuggestions, DirectSolrConnectionTest, HdfsBasicDistributedZkTest, DistributedTermsComponentTest, HighlighterConfigTest, MBeansHandlerTest, TestSystemIdResolver, UpdateParamsTest, TestSolrXml, DirectUpdateHandlerOptimizeTest, DocValuesTest, TestRTGBase, JSONWriterTest, ModifyConfFileTest, WordBreakSolrSpellCheckerTest, TestFunctionQuery, SearchHandlerTest, BadIndexSchemaTest, TestPhraseSuggestions, HdfsChaosMonkeySafeLeaderTest, TestRecovery, TestClassNameShortening, FastVectorHighlighterTest, TestDistributedGrouping, DeleteReplicaTest, BasicZkTest, TestSerializedLuceneMatchVersion, PreAnalyzedUpdateProcessorTest, RAMDirectoryFactoryTest, SuggesterTSTTest, TestWriterPerf, TestMultiCoreConfBootstrap, PluginInfoTest, TestFieldCollectionResource, ChangedSchemaMergeTest, TestSort, TestSurroundQueryParser, TestNRTOpen, TestAtomicUpdateErrorCases, ShardRoutingTest, TestManagedSchemaFieldResource, PreAnalyzedFieldTest, ClusterStateTest, SOLR749Test, SpatialFilterTest, TestSolrJ, TestPseudoReturnFields, TestCollationField, TestUtils, ExternalFileFieldSortTest, ChaosMonkeySafeLeaderTest, SynonymTokenizerTest, TestCloudManagedSchemaAddField, ShardSplitTest, SpellCheckCollatorTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestCSVResponseWriter, StressHdfsTest, XmlUpdateRequestHandlerTest, ResourceLoaderTest, AlternateDirectoryTest, SolrCoreTest, TestDocumentBuilder, HdfsBasicDistributedZk2Test, StatelessScriptUpdateProcessorFactoryTest, DistributedDebugComponentTest, LoggingHandlerTest, SolrXmlInZkTest, TestStressRecovery, TestSolrDeletionPolicy1, SolrRequestParserTest, TestCopyFieldCollectionResource, TestCoreContainer, TestStressUserVersions, TestDynamicFieldCollectionResource, TimeZoneUtilsTest, TestSearchPerf, PolyFieldTest, TestPerFieldSimilarity, TestBadConfig, TestTrie, TestQuerySenderNoQuery, TestMaxScoreQueryParser, OverseerTest, CoreContainerCoreInitFailuresTest, TestSolrXmlPersistence, RecoveryZkTest, TestSchemaVersionResource, ZkSolrClientTest, ZkNodePropsTest, TestFoldingMultitermQuery, TestCharFilters, CurrencyFieldOpenExchangeTest, DistributedSpellCheckComponentTest, TestUpdate, SuggesterFSTTest, MigrateRouteKeyTest, TestCodecSupport, OverseerCollectionProcessorTest, SolrInfoMBeanTest, TestLMDirichletSimilarityFactory, OpenExchangeRatesOrgProviderTest, HardAutoCommitTest, PeerSyncTest, AutoCommitTest, TestArbitraryIndexDir, HdfsRecoveryZkTest, FileBasedSpellCheckerTest, OpenCloseCoreStressTest, ConvertedLegacyTest, CustomCollectionTest, TestManagedSchema, ShardRoutingCustomTest, SuggesterWFSTTest, TestLazyCores, PathHierarchyTokenizerFactoryTest, TestFaceting, HdfsUnloadDistributedZkTest, SliceStateUpdateTest, TestAddFieldRealTimeGet, TriLevelCompositeIdRoutingTest, ParsingFieldUpdateProcessorsTest, LegacyHTMLStripCharFilterTest, ResponseLogComponentTest, TestBinaryField, BadComponentTest, SolrTestCaseJ4Test, TestGroupingSearch, OutputWriterTest, SystemInfoHandlerTest, HdfsLockFactoryTest, TestSolrXmlPersistor, SolrIndexSplitterTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, ClusterStateUpdateTest, LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, TermVectorComponentDistributedTest, TestRealTimeGet, TestStressReorder, TestStressVersions, TestRangeQuery, SimpleFacetsTest, TestSolr4Spatial, StatsComponentTest, QueryElevationComponentTest, TestFiltering, BasicFunctionalityTest, DirectUpdateHandlerTest, TestIndexSearcher, HighlighterTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SimplePostToolTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, CoreAdminHandlerTest, SuggesterTest, TestCSVLoader, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, TestWordDelimiterFilterFactory, QueryEqualityTest, SortByFunctionTest, TestRemoteStreaming, DistanceFunctionTest, XsltUpdateRequestHandlerTest, DebugComponentTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, DocumentBuilderTest, TestValueSourceCache, TermVectorComponentTest, RequiredFieldsTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, TestBinaryResponseWriter, TestLFUCache, TestSolrIndexConfig, CopyFieldTest, SolrIndexConfigTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, SampleTest, TestDocSet, TestElisionMultitermQuery, TestConfig, TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestXIncludeConfig, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestNumberUtils, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, TestFastOutputStream, ScriptEngineTest, URLClassifyProcessorTest, TestFastLRUCache, DateMathParserTest, TestSolrXMLSerializer, PrimUtilsTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, TestLRUCache, SliceStateTest, UUIDFieldTest, FileUtilsTest, CircularListTest, TestCollationKeyRangeQueries, AliasIntegrationTest, DeleteInactiveReplicaTest, TestDistribDocBasedVersion, TestModifyConfFiles, HdfsCollectionsAPIDistributedZkTest, HdfsSyncSliceTest, CachingDirectoryFactoryTest, TestInfoStreamLogging, TestNonNRTOpen]
[junit4] Completed in 1.18s, 2 tests, 1 failure <<< FAILURES!
[...truncated 97 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:489: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1276: 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: 341 suites, 1498 tests, 2 failures, 46 ignored (11 assumptions)
Total time: 64 minutes 7 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b114 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure