lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0-ea-b117) - Build # 3531 - Failure!
Date Mon, 02 Dec 2013 22:41:04 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3531/
Java: 32bit/jdk1.8.0-ea-b117 -server -XX:+UseParallelGC

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

Error Message:
Wrong doc count on shard1_0 expected:<128> but was:<67>

Stack Trace:
java.lang.AssertionError: Wrong doc count on shard1_0 expected:<128> but was:<67>
	at __randomizedtesting.SeedInfo.seed([1BD96294FB26DB68:9A3FEC8C8C79BB54]: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.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:477)
	at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:249)
	at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:113)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:843)
	at sun.reflect.GeneratedMethodAccessor50.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:483)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 20544 lines...]
   [junit4] Suite: org.apache.solr.cloud.ShardSplitTest
   [junit4]   2> 2751581 T9816 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /t_rw/
   [junit4]   2> 2751585 T9816 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ShardSplitTest-1386023881596
   [junit4]   2> 2751588 T9816 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 2751589 T9817 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 2751678 T9816 oasc.ZkTestServer.run start zk server on port:54732
   [junit4]   2> 2751681 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2751686 T9823 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7553c4 name:ZooKeeperConnection Watcher:127.0.0.1:54732 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2751687 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2751687 T9816 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 2751692 T9818 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x142b5760b840000, 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> 2751694 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2751696 T9825 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8870eb name:ZooKeeperConnection Watcher:127.0.0.1:54732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2751696 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2751696 T9816 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 2751701 T9816 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 2751704 T9816 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 2751707 T9816 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 2751709 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 2751709 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2751716 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema15.xml to /configs/conf1/schema.xml
   [junit4]   2> 2751717 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 2751721 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2751722 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2751725 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 2751725 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2751729 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 2751729 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2751733 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 2751733 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 2751736 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2751737 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2751741 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2751741 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2751745 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2751745 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2751749 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2751750 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2751753 T9816 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 2751753 T9816 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2751757 T9818 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x142b5760b840001, 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> 2752223 T9816 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2752242 T9816 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54739
   [junit4]   2> 2752244 T9816 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2752244 T9816 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2752245 T9816 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766
   [junit4]   2> 2752245 T9816 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766\'
   [junit4]   2> 2752283 T9816 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766\solr.xml
   [junit4]   2> 2752298 T9816 oasc.CoreContainer.<init> New CoreContainer 9010703
   [junit4]   2> 2752300 T9816 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766\]
   [junit4]   2> 2752301 T9816 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2752301 T9816 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2752301 T9816 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2752301 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2752301 T9816 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2752301 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2752301 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2752304 T9816 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2752304 T9816 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2752318 T9816 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2752319 T9816 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2752319 T9816 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2752319 T9816 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54732/solr
   [junit4]   2> 2752319 T9816 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2752326 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2752332 T9836 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c441f6 name:ZooKeeperConnection Watcher:127.0.0.1:54732 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2752333 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2752523 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2752525 T9838 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@df1303 name:ZooKeeperConnection Watcher:127.0.0.1:54732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2752526 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2752526 T9816 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 2752536 T9816 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 2752545 T9816 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 2752546 T9816 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54739_t_rw
   [junit4]   2> 2752548 T9816 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54739_t_rw
   [junit4]   2> 2752555 T9816 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 2752564 T9816 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 2752567 T9816 oasc.Overseer.start Overseer (id=90834461104799747-127.0.0.1:54739_t_rw-n_0000000000) starting
   [junit4]   2> 2752575 T9816 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 2752579 T9840 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 2752580 T9816 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 2752586 T9816 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 2752589 T9816 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2752593 T9839 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 2752597 T9841 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 2752597 T9841 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 2752600 T9841 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2752601 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2752603 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2752604 T9839 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:54739/t_rw",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54739_t_rw",
   [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> 2752604 T9839 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
   [junit4]   2> 2752605 T9839 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 2752609 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2752610 T9838 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> 2753537 T9841 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2753537 T9841 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766\collection1
   [junit4]   2> 2753537 T9841 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 2753539 T9841 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2753539 T9841 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 2753541 T9841 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766\collection1\'
   [junit4]   2> 2753543 T9841 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766/collection1/lib/classes/' to classloader
   [junit4]   2> 2753544 T9841 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766/collection1/lib/README' to classloader
   [junit4]   2> 2753569 T9841 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2753576 T9841 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2753579 T9841 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2753584 T9841 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 2753695 T9841 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2753696 T9841 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2753697 T9841 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2753701 T9841 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2753729 T9841 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2753729 T9841 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1386023881766\collection1\, dataDir=.\org.apache.solr.cloud.ShardSplitTest-1386023881595/control/data\
   [junit4]   2> 2753730 T9841 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@20b827
   [junit4]   2> 2753730 T9841 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1386023881595/control/data\
   [junit4]   2> 2753731 T9841 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ShardSplitTest-1386023881595/control/data\index/
   [junit4]   2> 2753731 T9841 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ShardSplitTest-1386023881595\control\data\index' doesn't exist. Creating new index...
   [junit4]   2> 2753731 T9841 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1386023881595/control/data\index
   [junit4]   2> 2753732 T9841 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 2753732 T9841 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18a412f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e91b35),segFN=segments_1,generation=1}
   [junit4]   2> 2753733 T9841 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2753735 T9841 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 2753735 T9841 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2753736 T9841 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2753736 T9841 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2753736 T9841 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2753736 T9841 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2753737 T9841 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2753737 T9841 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2753737 T9841 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 2753738 T9841 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 2753738 T9841 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 2753738 T9841 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 2753739 T9841 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 2753739 T9841 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 2753739 T9841 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 2753740 T9841 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 2753745 T9841 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2753749 T9841 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 2753749 T9841 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 2753750 T9841 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17328050515158627]
   [junit4]   2> 2753750 T9841 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18a412f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e91b35),segFN=segments_1,generation=1}
   [junit4]   2> 2753750 T9841 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2753751 T9841 oass.SolrIndexSearcher.<init> Opening Searcher@b5acb main
   [junit4]   2> 2753753 T9842 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b5acb main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 2753754 T9841 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 2753754 T9841 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54739/t_rw collection:control_collection shard:shard1
   [junit4]   2> 2753755 T9841 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 2753766 T9841 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 2753770 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2753770 T9841 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 2753770 T9841 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C2148 name=collection1 org.apache.solr.core.SolrCore@1a6f576 url=http://127.0.0.1:54739/t_rw/collection1 node=127.0.0.1:54739_t_rw C2148_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:54739/t_rw, core=collection1, node_name=127.0.0.1:54739_t_rw}
   [junit4]   2> 2753770 T9841 C2148 P54739 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54739/t_rw/collection1/
   [junit4]   2> 2753771 T9841 C2148 P54739 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 2753771 T9841 C2148 P54739 oasc.SyncStrategy.syncToMe http://127.0.0.1:54739/t_rw/collection1/ has no replicas
   [junit4]   2> 2753771 T9841 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54739/t_rw/collection1/ shard1
   [junit4]   2> 2753772 T9841 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 2753773 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2753779 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2753783 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2753788 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2753888 T9838 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> 2753933 T9841 oasc.ZkController.register We are http://127.0.0.1:54739/t_rw/collection1/ and leader is http://127.0.0.1:54739/t_rw/collection1/
   [junit4]   2> 2753933 T9841 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54739/t_rw
   [junit4]   2> 2753933 T9841 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 2753933 T9841 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 2753934 T9841 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 2753935 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2753936 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2753936 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2753936 T9841 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2753938 T9816 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2753938 T9816 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2753938 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2753939 T9839 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:54739/t_rw",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54739_t_rw",
   [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> 2753941 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2753943 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2753945 T9845 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f0fe36 name:ZooKeeperConnection Watcher:127.0.0.1:54732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2753945 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2753946 T9816 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2753949 T9816 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 2754047 T9838 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> 2754047 T9845 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> 2754433 T9816 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2754438 T9816 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54751
   [junit4]   2> 2754438 T9816 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2754438 T9816 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2754438 T9816 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958
   [junit4]   2> 2754438 T9816 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958\'
   [junit4]   2> 2754466 T9816 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958\solr.xml
   [junit4]   2> 2754476 T9816 oasc.CoreContainer.<init> New CoreContainer 11335006
   [junit4]   2> 2754476 T9816 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958\]
   [junit4]   2> 2754478 T9816 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2754478 T9816 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2754478 T9816 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2754478 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2754478 T9816 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2754478 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2754480 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2754480 T9816 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2754480 T9816 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2754488 T9816 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2754489 T9816 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2754489 T9816 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2754489 T9816 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54732/solr
   [junit4]   2> 2754489 T9816 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2754493 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2754502 T9856 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@44f09 name:ZooKeeperConnection Watcher:127.0.0.1:54732 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2754502 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2754506 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2754509 T9858 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a613f name:ZooKeeperConnection Watcher:127.0.0.1:54732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2754510 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2754516 T9816 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2755501 T9816 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54751_t_rw
   [junit4]   2> 2755503 T9816 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54751_t_rw
   [junit4]   2> 2755507 T9838 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2755507 T9858 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2755507 T9845 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2755514 T9859 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 2755514 T9859 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 2755516 T9859 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2755516 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2755516 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2755517 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2755519 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2755519 T9839 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:54751/t_rw",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54751_t_rw",
   [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> 2755520 T9839 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
   [junit4]   2> 2755520 T9839 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 2755523 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2755625 T9838 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> 2755625 T9858 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> 2755625 T9845 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> 2756499 T9859 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2756500 T9859 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958\collection1
   [junit4]   2> 2756500 T9859 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 2756501 T9859 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2756501 T9859 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 2756503 T9859 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958\collection1\'
   [junit4]   2> 2756505 T9859 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958/collection1/lib/classes/' to classloader
   [junit4]   2> 2756506 T9859 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958/collection1/lib/README' to classloader
   [junit4]   2> 2756534 T9859 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2756541 T9859 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2756544 T9859 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2756549 T9859 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 2756659 T9859 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2756662 T9859 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2756663 T9859 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2756667 T9859 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2756693 T9859 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2756693 T9859 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty1-1386023883958\collection1\, dataDir=.\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty1\
   [junit4]   2> 2756694 T9859 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@20b827
   [junit4]   2> 2756694 T9859 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty1\
   [junit4]   2> 2756695 T9859 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty1\index/
   [junit4]   2> 2756695 T9859 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ShardSplitTest-1386023881595\jetty1\index' doesn't exist. Creating new index...
   [junit4]   2> 2756695 T9859 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty1\index
   [junit4]   2> 2756696 T9859 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 2756696 T9859 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@fbc315 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1c05),segFN=segments_1,generation=1}
   [junit4]   2> 2756697 T9859 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2756699 T9859 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 2756700 T9859 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2756700 T9859 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2756700 T9859 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2756701 T9859 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2756701 T9859 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2756701 T9859 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2756702 T9859 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2756702 T9859 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 2756702 T9859 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 2756703 T9859 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 2756703 T9859 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 2756703 T9859 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 2756704 T9859 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 2756704 T9859 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 2756705 T9859 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 2756711 T9859 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2756714 T9859 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 2756714 T9859 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 2756715 T9859 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17328050515158627]
   [junit4]   2> 2756715 T9859 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@fbc315 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1c05),segFN=segments_1,generation=1}
   [junit4]   2> 2756715 T9859 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2756715 T9859 oass.SolrIndexSearcher.<init> Opening Searcher@207c2c main
   [junit4]   2> 2756718 T9860 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@207c2c main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 2756720 T9859 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 2756720 T9859 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54751/t_rw collection:collection1 shard:shard2
   [junit4]   2> 2756721 T9859 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
   [junit4]   2> 2756732 T9859 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
   [junit4]   2> 2756735 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756735 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756736 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756736 T9859 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 2756737 T9859 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C2149 name=collection1 org.apache.solr.core.SolrCore@17b3dc1 url=http://127.0.0.1:54751/t_rw/collection1 node=127.0.0.1:54751_t_rw C2149_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:54751/t_rw, core=collection1, node_name=127.0.0.1:54751_t_rw}
   [junit4]   2> 2756737 T9859 C2149 P54751 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54751/t_rw/collection1/
   [junit4]   2> 2756737 T9859 C2149 P54751 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 2756737 T9859 C2149 P54751 oasc.SyncStrategy.syncToMe http://127.0.0.1:54751/t_rw/collection1/ has no replicas
   [junit4]   2> 2756737 T9859 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54751/t_rw/collection1/ shard2
   [junit4]   2> 2756737 T9859 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
   [junit4]   2> 2756738 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2756743 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756748 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756752 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756853 T9858 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> 2756854 T9838 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> 2756853 T9845 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> 2756897 T9859 oasc.ZkController.register We are http://127.0.0.1:54751/t_rw/collection1/ and leader is http://127.0.0.1:54751/t_rw/collection1/
   [junit4]   2> 2756897 T9859 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54751/t_rw
   [junit4]   2> 2756897 T9859 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 2756897 T9859 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 2756898 T9859 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 2756899 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756900 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756900 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2756901 T9859 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2756903 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2756904 T9816 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2756905 T9839 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:54751/t_rw",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54751_t_rw",
   [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> 2756905 T9816 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2756911 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2757013 T9858 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> 2757014 T9838 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> 2757014 T9845 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> 2757410 T9816 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2757415 T9816 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54760
   [junit4]   2> 2757416 T9816 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2757417 T9816 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2757417 T9816 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917
   [junit4]   2> 2757417 T9816 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917\'
   [junit4]   2> 2757444 T9816 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917\solr.xml
   [junit4]   2> 2757456 T9816 oasc.CoreContainer.<init> New CoreContainer 26629095
   [junit4]   2> 2757457 T9816 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917\]
   [junit4]   2> 2757457 T9816 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2757458 T9816 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2757458 T9816 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2757458 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2757459 T9816 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2757459 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2757460 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2757460 T9816 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2757461 T9816 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2757469 T9816 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2757470 T9816 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2757470 T9816 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2757471 T9816 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54732/solr
   [junit4]   2> 2757471 T9816 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2757473 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2757481 T9873 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b478b3 name:ZooKeeperConnection Watcher:127.0.0.1:54732 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2757481 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2757490 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2757501 T9875 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@396474 name:ZooKeeperConnection Watcher:127.0.0.1:54732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2757501 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2757506 T9816 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2758489 T9816 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54760_t_rw
   [junit4]   2> 2758492 T9816 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54760_t_rw
   [junit4]   2> 2758495 T9838 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2758496 T9875 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2758496 T9845 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2758497 T9858 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2758504 T9876 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 2758505 T9876 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 2758506 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2758506 T9876 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2758507 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2758507 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2758509 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2758510 T9839 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:54760/t_rw",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54760_t_rw",
   [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> 2758511 T9839 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 2758511 T9839 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 2758515 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2758516 T9838 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> 2758516 T9858 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> 2758517 T9845 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> 2758516 T9875 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> 2759487 T9876 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2759487 T9876 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917\collection1
   [junit4]   2> 2759487 T9876 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 2759488 T9876 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2759489 T9876 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 2759491 T9876 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917\collection1\'
   [junit4]   2> 2759493 T9876 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917/collection1/lib/classes/' to classloader
   [junit4]   2> 2759494 T9876 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917/collection1/lib/README' to classloader
   [junit4]   2> 2759522 T9876 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2759530 T9876 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2759532 T9876 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2759538 T9876 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 2759645 T9876 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2759645 T9876 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2759647 T9876 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2759651 T9876 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2759685 T9876 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2759685 T9876 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ShardSplitTest-jetty2-1386023886917\collection1\, dataDir=.\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty2\
   [junit4]   2> 2759686 T9876 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@20b827
   [junit4]   2> 2759686 T9876 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty2\
   [junit4]   2> 2759687 T9876 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty2\index/
   [junit4]   2> 2759687 T9876 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ShardSplitTest-1386023881595\jetty2\index' doesn't exist. Creating new index...
   [junit4]   2> 2759687 T9876 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty2\index
   [junit4]   2> 2759688 T9876 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 2759688 T9876 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@91e776 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eb9bac),segFN=segments_1,generation=1}
   [junit4]   2> 2759689 T9876 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2759691 T9876 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 2759692 T9876 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2759692 T9876 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2759692 T9876 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2759693 T9876 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2759693 T9876 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2759693 T9876 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2759694 T9876 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2759694 T9876 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 2759694 T9876 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 2759695 T9876 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 2759696 T9876 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 2759696 T9876 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 2759696 T9876 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 2759697 T9876 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 2759697 T9876 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 2759704 T9876 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2759707 T9876 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 2759707 T9876 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 2759708 T9876 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17328050515158627]
   [junit4]   2> 2759708 T9876 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@91e776 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eb9bac),segFN=segments_1,generation=1}
   [junit4]   2> 2759708 T9876 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2759708 T9876 oass.SolrIndexSearcher.<init> Opening Searcher@307b1c main
   [junit4]   2> 2759711 T9877 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@307b1c main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 2759713 T9876 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 2759713 T9876 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54760/t_rw collection:collection1 shard:shard1
   [junit4]   2> 2759714 T9876 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
   [junit4]   2> 2759725 T9876 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 2759728 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2759728 T9876 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 2759729 T9876 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C2150 name=collection1 org.apache.solr.core.SolrCore@17c35fc url=http://127.0.0.1:54760/t_rw/collection1 node=127.0.0.1:54760_t_rw C2150_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:54760/t_rw, core=collection1, node_name=127.0.0.1:54760_t_rw}
   [junit4]   2> 2759729 T9876 C2150 P54760 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54760/t_rw/collection1/
   [junit4]   2> 2759729 T9876 C2150 P54760 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 2759729 T9876 C2150 P54760 oasc.SyncStrategy.syncToMe http://127.0.0.1:54760/t_rw/collection1/ has no replicas
   [junit4]   2> 2759729 T9876 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54760/t_rw/collection1/ shard1
   [junit4]   2> 2759729 T9876 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
   [junit4]   2> 2759731 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2759738 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2759745 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2759847 T9838 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> 2759847 T9875 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> 2759847 T9845 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> 2759847 T9858 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> 2759891 T9876 oasc.ZkController.register We are http://127.0.0.1:54760/t_rw/collection1/ and leader is http://127.0.0.1:54760/t_rw/collection1/
   [junit4]   2> 2759891 T9876 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54760/t_rw
   [junit4]   2> 2759891 T9876 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 2759891 T9876 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 2759891 T9876 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 2759893 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2759893 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2759893 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2759894 T9876 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2759896 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2759896 T9816 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2759896 T9816 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2759897 T9839 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:54760/t_rw",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54760_t_rw",
   [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> 2759901 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2760004 T9875 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> 2760004 T9845 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> 2760005 T9838 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> 2760005 T9858 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> 2760391 T9816 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2760396 T9816 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54769
   [junit4]   2> 2760396 T9816 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2760396 T9816 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2760396 T9816 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907
   [junit4]   2> 2760396 T9816 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907\'
   [junit4]   2> 2760424 T9816 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907\solr.xml
   [junit4]   2> 2760434 T9816 oasc.CoreContainer.<init> New CoreContainer 8676408
   [junit4]   2> 2760434 T9816 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907\]
   [junit4]   2> 2760436 T9816 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2760436 T9816 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2760436 T9816 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2760436 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2760436 T9816 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2760436 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2760438 T9816 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2760438 T9816 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2760438 T9816 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2760444 T9816 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2760444 T9816 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2760444 T9816 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2760444 T9816 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54732/solr
   [junit4]   2> 2760446 T9816 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2760449 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2760460 T9889 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10b9a78 name:ZooKeeperConnection Watcher:127.0.0.1:54732 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2760461 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2760470 T9818 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x142b5760b840009, 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> 2760471 T9816 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2760474 T9891 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10f31f2 name:ZooKeeperConnection Watcher:127.0.0.1:54732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2760474 T9816 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2760479 T9816 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2761463 T9816 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54769_t_rw
   [junit4]   2> 2761465 T9816 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54769_t_rw
   [junit4]   2> 2761469 T9891 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2761469 T9875 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2761470 T9845 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2761470 T9838 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2761470 T9858 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2761477 T9892 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 2761477 T9892 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 2761479 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2761479 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2761479 T9892 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2761480 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2761482 T9839 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2761483 T9839 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:54769/t_rw",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54769_t_rw",
   [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> 2761483 T9839 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 2761483 T9839 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 2761488 T9838 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2761588 T9891 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> 2761589 T9838 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> 2761589 T9858 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> 2761589 T9845 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> 2761589 T9875 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> 2762460 T9892 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2762460 T9892 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907\collection1
   [junit4]   2> 2762460 T9892 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 2762461 T9892 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2762462 T9892 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 2762464 T9892 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907\collection1\'
   [junit4]   2> 2762467 T9892 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907/collection1/lib/classes/' to classloader
   [junit4]   2> 2762469 T9892 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907/collection1/lib/README' to classloader
   [junit4]   2> 2762494 T9892 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2762501 T9892 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2762504 T9892 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2762510 T9892 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 2762623 T9892 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2762624 T9892 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2762625 T9892 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2762628 T9892 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2762670 T9892 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2762671 T9892 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ShardSplitTest-jetty3-1386023889907\collection1\, dataDir=.\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty3\
   [junit4]   2> 2762671 T9892 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@20b827
   [junit4]   2> 2762672 T9892 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty3\
   [junit4]   2> 2762672 T9892 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty3\index/
   [junit4]   2> 2762672 T9892 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ShardSplitTest-1386023881595\jetty3\index' doesn't exist. Creating new index...
   [junit4]   2> 2762672 T9892 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1386023881595/jetty3\index
   [junit4]   2> 2762672 T9892 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 2762673 T9892 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@47cb4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e5456f),segFN=segments_1,generation=1}
   [junit4]   2> 2762673 T9892 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2762676 T9892 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 2762676 T9892 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2762676 T9892 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2762677 T9892 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [jun

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

earch -Dtests.seed=1BD96294FB26DB68 -Dtests.slow=true -Dtests.locale=ar_QA -Dtests.timezone=Pacific/Rarotonga -Dtests.file.encoding=Cp1252
   [junit4] FAILURE 59.8s | ShardSplitTest.testDistribSearch <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Wrong doc count on shard1_0 expected:<128> but was:<67>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([1BD96294FB26DB68:9A3FEC8C8C79BB54]:0)
   [junit4]    > 	at org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:477)
   [junit4]    > 	at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:249)
   [junit4]    > 	at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:113)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:843)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 2811393 T9816 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 59819 T9815 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting, sim=DefaultSimilarity, locale=ar_QA, timezone=Pacific/Rarotonga
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=2,free=142447648,total=349700096
   [junit4]   2> NOTE: All tests run in this JVM: [TestCopyFieldCollectionResource, TestRandomMergePolicy, TermVectorComponentDistributedTest, RequestHandlersTest, PolyFieldTest, LeaderElectionTest, BadComponentTest, DebugComponentTest, TestNonNRTOpen, TestDistributedSearch, TestFaceting, DistanceFunctionTest, RAMDirectoryFactoryTest, SolrTestCaseJ4Test, TestLMDirichletSimilarityFactory, DistributedSuggesterComponentTest, TestMaxScoreQueryParser, InfoHandlerTest, DocumentBuilderTest, TestIndexingPerformance, TestDistributedMissingSort, HdfsBasicDistributedZk2Test, SyncSliceTest, TestJoin, TestHighFrequencyDictionaryFactory, CSVRequestHandlerTest, TestAnalyzeInfixSuggestions, TestFastWriter, TestJmxMonitoredMap, CacheHeaderTest, TestCollationFieldDocValues, IndexSchemaRuntimeFieldTest, ZkCLITest, OpenExchangeRatesOrgProviderTest, EchoParamsTest, TestCollationField, HdfsDirectoryTest, HdfsChaosMonkeySafeLeaderTest, TestSolrXmlPersistor, PrimUtilsTest, ZkNodePropsTest, ResponseLogComponentTest, OpenCloseCoreStressTest, TestCloudManagedSchemaAddField, SimpleFacetsTest, SuggesterTSTTest, SpellCheckComponentTest, TestDefaultSearchFieldResource, TestDynamicFieldCollectionResource, TestIndexSearcher, BinaryUpdateRequestHandlerTest, SolrIndexConfigTest, CachingDirectoryFactoryTest, UpdateParamsTest, TestStressReorder, StressHdfsTest, TestPerFieldSimilarity, TestOmitPositions, PluginInfoTest, TestBinaryField, DocValuesTest, ShardRoutingCustomTest, TestFastOutputStream, TestSolrXMLSerializer, ClusterStateUpdateTest, DirectSolrSpellCheckerTest, TestStressRecovery, RequiredFieldsTest, PathHierarchyTokenizerFactoryTest, TestCSVResponseWriter, QueryParsingTest, TestIBSimilarityFactory, MinimalSchemaTest, ScriptEngineTest, ZkSolrClientTest, TestImplicitCoreProperties, SolrIndexSplitterTest, MultiTermTest, CollectionsAPIDistributedZkTest, SuggesterWFSTTest, ModifyConfFileTest, ConvertedLegacyTest, HdfsRecoveryZkTest, ReturnFieldsTest, BasicZkTest, TestAnalyzedSuggestions, TestAddFieldRealTimeGet, TestQuerySenderListener, TestCustomSort, TestReversedWildcardFilterFactory, MBeansHandlerTest, DocumentAnalysisRequestHandlerTest, TestDistribDocBasedVersion, SolrXmlInZkTest, TestStressVersions, TestPhraseSuggestions, TestReplicationHandler, HardAutoCommitTest, BadIndexSchemaTest, TestSerializedLuceneMatchVersion, TestExtendedDismaxParser, TestManagedSchema, TestInfoStreamLogging, TestSolrXmlPersistence, NotRequiredUniqueKeyTest, OverseerCollectionProcessorTest, TestManagedSchemaFieldResource, HdfsCollectionsAPIDistributedZkTest, TestSolrXml, TestNRTOpen, TestQueryTypes, DateFieldTest, SpatialFilterTest, DocValuesMissingTest, TestRecoveryHdfs, TestSolrDeletionPolicy1, BadCopyFieldTest, ResourceLoaderTest, TestFileDictionaryLookup, TestSolrCoreProperties, TimeZoneUtilsTest, TestPostingsSolrHighlighter, SuggesterFSTTest, SolrCoreCheckLockOnStartupTest, AbstractAnalyticsStatsTest, QueryEqualityTest, ParsingFieldUpdateProcessorsTest, SynonymTokenizerTest, LegacyHTMLStripCharFilterTest, SuggesterTest, FieldFacetTest, TestRealTimeGet, TestSchemaVersionResource, AddSchemaFieldsUpdateProcessorFactoryTest, AliasIntegrationTest, OverseerTest, TestFieldCollectionResource, TestCharFilters, TriLevelCompositeIdRoutingTest, TestBadConfig, BasicDistributedZk2Test, FieldFacetExtrasTest, AlternateDirectoryTest, BlockCacheTest, FieldAnalysisRequestHandlerTest, TestMergePolicyConfig, TestRecovery, DeleteInactiveReplicaTest, HighlighterConfigTest, CoreAdminCreateDiscoverTest, BJQParserTest, DistributedTermsComponentTest, SolrInfoMBeanTest, TestSort, NoCacheHeaderTest, TestBinaryResponseWriter, TestSolrQueryParser, TestCloudManagedSchema, AnalysisAfterCoreReloadTest, TestRandomFaceting, TestCodecSupport, QueryResultKeyTest, SimplePostToolTest, UpdateRequestProcessorFactoryTest, TestFastLRUCache, DeleteReplicaTest, SampleTest, SpellingQueryConverterTest, CircularListTest, AddBlockUpdateTest, TestDefaultSimilarityFactory, TestStressUserVersions, NumericFieldsTest, DateMathParserTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, LeaderElectionIntegrationTest, ShardRoutingTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestZkChroot, TestRandomDVFaceting, SliceStateUpdateTest, AutoCommitTest, TestDistributedGrouping, TestHashPartitioner, DistributedSpellCheckComponentTest, ZkControllerTest, TestMultiCoreConfBootstrap, TestReload, TestRangeQuery, TestCoreContainer, TestSolr4Spatial, SolrCoreTest, StatsComponentTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, PeerSyncTest, TestFiltering, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, DirectUpdateHandlerTest, HighlighterTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, CoreAdminHandlerTest, SolrRequestParserTest, TestFoldingMultitermQuery, TestStressLucene, TestTrie, TestCSVLoader, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, TestWriterPerf, PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, TestValueSourceCache, TermVectorComponentTest, MoreLikeThisHandlerTest, TestArbitraryIndexDir, FastVectorHighlighterTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, JSONWriterTest, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestLFUCache, SOLR749Test, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, TestSolrDeletionPolicy2, TestDocSet, TestSearchPerf, TestElisionMultitermQuery, TestConfig, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, DirectSolrConnectionTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestNumberUtils, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, PreAnalyzedFieldTest, TestSystemIdResolver, TestSuggestSpellingConverter, DOMUtilTest, ClusterStateTest, TestSolrJ, TestLRUCache, TestUtils, TestDocumentBuilder, SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, TestRTGBase, NoFacetTest, ExpressionTest, AbstractAnalyticsFacetTest, QueryFacetTest, RangeFacetTest, FunctionTest, AssignTest, CustomCollectionTest, DeleteShardTest, MigrateRouteKeyTest, ShardSplitTest]
   [junit4] Completed in 60.07s, 1 test, 1 failure <<< FAILURES!

[...truncated 102 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:420: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:400: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1276: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:909: There were test failures: 357 suites, 1570 tests, 1 failure, 39 ignored (11 assumptions)

Total time: 81 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b117 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message