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 (64bit/jdk1.7.0_21) - Build # 2898 - Still Failing!
Date Thu, 06 Jun 2013 22:27:07 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2898/
Java: 64bit/jdk1.7.0_21 -XX:+UseCompressedOops -XX:+UseG1GC

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

Error Message:
Server at http://127.0.0.1:50085/_/u returned non ok status:500, message:Server Error

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Server at http://127.0.0.1:50085/_/u returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([F4315BC07879041B:75D7D5D80F266427]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:385)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
	at org.apache.solr.cloud.ShardSplitTest.splitShard(ShardSplitTest.java:228)
	at org.apache.solr.cloud.ChaosMonkeyShardSplitTest.doTest(ChaosMonkeyShardSplitTest.java:136)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:815)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	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:722)




Build Log:
[...truncated 9976 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.ChaosMonkeyShardSplitTest
[junit4:junit4]   2> 2431429 T7423 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /_/u
[junit4:junit4]   2> 2431435 T7423 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ChaosMonkeyShardSplitTest-1370557519959
[junit4:junit4]   2> 2431438 T7423 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 2431439 T7424 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 2431558 T7423 oasc.ZkTestServer.run start zk server on port:50066
[junit4:junit4]   2> 2431560 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2431572 T7430 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@868d9ee name:ZooKeeperConnection Watcher:127.0.0.1:50066 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2431572 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2431574 T7423 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 2431583 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2431587 T7432 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4d8a5dee name:ZooKeeperConnection Watcher:127.0.0.1:50066/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2431587 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2431587 T7423 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 2431595 T7423 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 2431602 T7423 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 2431607 T7423 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 2431613 T7423 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:junit4]   2> 2431614 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2431624 T7423 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:junit4]   2> 2431625 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 2431633 T7423 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:junit4]   2> 2431634 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 2431640 T7423 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:junit4]   2> 2431641 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 2431648 T7423 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:junit4]   2> 2431649 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 2431655 T7423 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:junit4]   2> 2431656 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 2431662 T7423 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:junit4]   2> 2431662 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 2431669 T7423 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:junit4]   2> 2431670 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 2431676 T7423 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:junit4]   2> 2431676 T7423 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 2432135 T7423 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 2432143 T7423 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50073
[junit4:junit4]   2> 2432144 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2432144 T7423 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2432145 T7423 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209
[junit4:junit4]   2> 2432145 T7423 oasc.CoreContainer$Initializer.initialize looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209\solr.xml
[junit4:junit4]   2> 2432145 T7423 oasc.CoreContainer.<init> New CoreContainer 929301241
[junit4:junit4]   2> 2432147 T7423 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209\'
[junit4:junit4]   2> 2432147 T7423 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209\'
[junit4:junit4]   2> 2432293 T7423 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 2432293 T7423 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2432294 T7423 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 2432294 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2432296 T7423 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2432296 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2432296 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2432297 T7423 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2432297 T7423 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2432298 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 2432307 T7423 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 2432308 T7423 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50066/solr
[junit4:junit4]   2> 2432308 T7423 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 2432310 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2432328 T7443 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c72db8a name:ZooKeeperConnection Watcher:127.0.0.1:50066 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2432329 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2432335 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 2432348 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2432369 T7445 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3ef96cba name:ZooKeeperConnection Watcher:127.0.0.1:50066/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2432370 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2432394 T7423 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4:junit4]   2> 2432440 T7423 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4:junit4]   2> 2432468 T7423 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 2432488 T7423 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50073__%2Fu
[junit4:junit4]   2> 2432510 T7423 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50073__%2Fu
[junit4:junit4]   2> 2432537 T7423 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 2432615 T7423 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 2432639 T7423 oasc.Overseer.start Overseer (id=89820857628753923-127.0.0.1:50073__%2Fu-n_0000000000) starting
[junit4:junit4]   2> 2432708 T7423 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4:junit4]   2> 2432768 T7447 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 2432769 T7423 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 2432792 T7423 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4:junit4]   2> 2432826 T7423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2432831 T7446 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 2432838 T7448 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209\collection1
[junit4:junit4]   2> 2432838 T7448 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 2432839 T7448 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2432839 T7448 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 2432841 T7448 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209\collection1\'
[junit4:junit4]   2> 2432843 T7448 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2432845 T7448 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209/collection1/lib/README' to classloader
[junit4:junit4]   2> 2432906 T7448 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2432976 T7448 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2432979 T7448 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 2433025 T7448 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 2433781 T7448 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2433786 T7448 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2433788 T7448 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2433799 T7448 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2433838 T7448 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2433839 T7448 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-controljetty-1370557520209\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/control/data\
[junit4:junit4]   2> 2433839 T7448 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e774999
[junit4:junit4]   2> 2433839 T7448 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2433839 T7448 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/control/data\
[junit4:junit4]   2> 2433839 T7448 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/control/data\index/
[junit4:junit4]   2> 2433842 T7448 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2433842 T7448 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/control/data\index
[junit4:junit4]   2> 2433846 T7448 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@394d82b7 lockFactory=org.apache.lucene.store.NativeFSLockFactory@62a41d5a),segFN=segments_1,generation=1}
[junit4:junit4]   2> 2433847 T7448 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 2433851 T7448 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2433851 T7448 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2433852 T7448 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2433853 T7448 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2433853 T7448 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2433853 T7448 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2433853 T7448 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2433855 T7448 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2433855 T7448 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2433867 T7448 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2433875 T7448 oass.SolrIndexSearcher.<init> Opening Searcher@250b9e08 main
[junit4:junit4]   2> 2433875 T7448 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\control\data\tlog
[junit4:junit4]   2> 2433877 T7448 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2433877 T7448 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2433883 T7448 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 2433883 T7448 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2433884 T7449 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@250b9e08 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2434597 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2434598 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50073__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50073/_/u"}
[junit4:junit4]   2> 2434598 T7446 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 2434598 T7446 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard=shard1
[junit4:junit4]   2> 2434612 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2435075 T7448 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2435075 T7448 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50073/_/u collection:control_collection shard:shard1
[junit4:junit4]   2> 2435078 T7448 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 2435112 T7448 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2435125 T7448 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2435125 T7448 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2435125 T7448 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50073/_/u/collection1/
[junit4:junit4]   2> 2435125 T7448 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2435128 T7448 oasc.SyncStrategy.syncToMe http://127.0.0.1:50073/_/u/collection1/ has no replicas
[junit4:junit4]   2> 2435128 T7448 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50073/_/u/collection1/
[junit4:junit4]   2> 2435128 T7448 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 2436381 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2436403 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2436472 T7448 oasc.ZkController.register We are http://127.0.0.1:50073/_/u/collection1/ and leader is http://127.0.0.1:50073/_/u/collection1/
[junit4:junit4]   2> 2436472 T7448 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50073/_/u
[junit4:junit4]   2> 2436472 T7448 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2436472 T7448 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 2436473 T7448 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2436478 T7448 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2436480 T7423 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2436481 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2436481 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2436489 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 2436494 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2436499 T7452 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@711a3970 name:ZooKeeperConnection Watcher:127.0.0.1:50066/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2436499 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2436502 T7423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2436506 T7423 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 2436987 T7423 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 2436991 T7423 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50085
[junit4:junit4]   2> 2436992 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2436992 T7423 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2436993 T7423 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029
[junit4:junit4]   2> 2436993 T7423 oasc.CoreContainer$Initializer.initialize looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029\solr.xml
[junit4:junit4]   2> 2436995 T7423 oasc.CoreContainer.<init> New CoreContainer 1315897432
[junit4:junit4]   2> 2436995 T7423 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029\'
[junit4:junit4]   2> 2436996 T7423 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029\'
[junit4:junit4]   2> 2437121 T7423 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 2437121 T7423 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2437122 T7423 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 2437122 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2437123 T7423 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2437123 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2437123 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2437124 T7423 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2437124 T7423 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2437125 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 2437133 T7423 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 2437134 T7423 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50066/solr
[junit4:junit4]   2> 2437136 T7423 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 2437141 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2437158 T7463 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@695060a name:ZooKeeperConnection Watcher:127.0.0.1:50066 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2437160 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2437163 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 2437172 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2437176 T7465 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5979f3c7 name:ZooKeeperConnection Watcher:127.0.0.1:50066/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2437177 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2437186 T7423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2438176 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2438177 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50073__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50073/_/u"}
[junit4:junit4]   2> 2438190 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2438190 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2438191 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2438365 T7423 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50085__%2Fu
[junit4:junit4]   2> 2438369 T7423 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50085__%2Fu
[junit4:junit4]   2> 2438373 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2438376 T7465 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2438376 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2438377 T7452 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2438377 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2438379 T7445 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2438389 T7466 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029\collection1
[junit4:junit4]   2> 2438391 T7466 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2438392 T7466 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2438392 T7466 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2438394 T7466 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029\collection1\'
[junit4:junit4]   2> 2438397 T7466 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2438397 T7466 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029/collection1/lib/README' to classloader
[junit4:junit4]   2> 2438460 T7466 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2438534 T7466 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2438537 T7466 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 2438543 T7466 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 2439306 T7466 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2439311 T7466 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2439314 T7466 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2439323 T7466 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2439373 T7466 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2439374 T7466 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty1-1370557525029\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty1\
[junit4:junit4]   2> 2439374 T7466 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e774999
[junit4:junit4]   2> 2439374 T7466 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2439374 T7466 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty1\
[junit4:junit4]   2> 2439374 T7466 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty1\index/
[junit4:junit4]   2> 2439377 T7466 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2439377 T7466 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty1\index
[junit4:junit4]   2> 2439382 T7466 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53bc97e lockFactory=org.apache.lucene.store.NativeFSLockFactory@6814cd77),segFN=segments_1,generation=1}
[junit4:junit4]   2> 2439382 T7466 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 2439387 T7466 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2439387 T7466 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2439388 T7466 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2439388 T7466 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2439391 T7466 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2439391 T7466 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2439391 T7466 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2439392 T7466 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2439392 T7466 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2439405 T7466 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2439413 T7466 oass.SolrIndexSearcher.<init> Opening Searcher@78509942 main
[junit4:junit4]   2> 2439415 T7466 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\jetty1\tlog
[junit4:junit4]   2> 2439417 T7466 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2439417 T7466 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2439424 T7467 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78509942 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2439426 T7466 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 2439426 T7466 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2440038 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2440039 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50085__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50085/_/u"}
[junit4:junit4]   2> 2440039 T7446 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 2440039 T7446 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard=shard1
[junit4:junit4]   2> 2440051 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2440051 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2440051 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2440725 T7466 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2440725 T7466 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50085/_/u collection:collection1 shard:shard1
[junit4:junit4]   2> 2440726 T7466 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 2440745 T7466 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2440752 T7466 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2440752 T7466 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2440752 T7466 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50085/_/u/collection1/
[junit4:junit4]   2> 2440752 T7466 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2440754 T7466 oasc.SyncStrategy.syncToMe http://127.0.0.1:50085/_/u/collection1/ has no replicas
[junit4:junit4]   2> 2440754 T7466 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50085/_/u/collection1/
[junit4:junit4]   2> 2440754 T7466 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 2441998 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2442026 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2442026 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2442026 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2442080 T7466 oasc.ZkController.register We are http://127.0.0.1:50085/_/u/collection1/ and leader is http://127.0.0.1:50085/_/u/collection1/
[junit4:junit4]   2> 2442080 T7466 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50085/_/u
[junit4:junit4]   2> 2442080 T7466 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2442080 T7466 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 2442081 T7466 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2442085 T7466 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2442088 T7423 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2442089 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2442090 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2442613 T7423 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 2442620 T7423 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50094
[junit4:junit4]   2> 2442621 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2442621 T7423 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2442622 T7423 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630
[junit4:junit4]   2> 2442622 T7423 oasc.CoreContainer$Initializer.initialize looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630\solr.xml
[junit4:junit4]   2> 2442622 T7423 oasc.CoreContainer.<init> New CoreContainer 418108559
[junit4:junit4]   2> 2442623 T7423 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630\'
[junit4:junit4]   2> 2442623 T7423 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630\'
[junit4:junit4]   2> 2442766 T7423 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 2442766 T7423 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2442766 T7423 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 2442767 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2442767 T7423 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2442767 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2442770 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2442772 T7423 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2442772 T7423 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2442773 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 2442787 T7423 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 2442789 T7423 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50066/solr
[junit4:junit4]   2> 2442790 T7423 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 2442792 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2442811 T7479 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@69cd2b1 name:ZooKeeperConnection Watcher:127.0.0.1:50066 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2442813 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2442816 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 2442818 T7425 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13f1b9818620007, likely client has closed socket
[junit4:junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4]   2> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 
[junit4:junit4]   2> 2442826 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2442831 T7481 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8f56496 name:ZooKeeperConnection Watcher:127.0.0.1:50066/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2442831 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2442843 T7423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2443983 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2443985 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50085__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50085/_/u"}
[junit4:junit4]   2> 2444010 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2444010 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2444010 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2444010 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2444141 T7423 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50094__%2Fu
[junit4:junit4]   2> 2444149 T7423 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50094__%2Fu
[junit4:junit4]   2> 2444163 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2444164 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 2444168 T7452 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 2444169 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2444172 T7465 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 2444172 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2444182 T7481 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 2444184 T7445 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 2444197 T7482 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630\collection1
[junit4:junit4]   2> 2444197 T7482 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2444198 T7482 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2444198 T7482 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2444201 T7482 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630\collection1\'
[junit4:junit4]   2> 2444203 T7482 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2444205 T7482 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630/collection1/lib/README' to classloader
[junit4:junit4]   2> 2444290 T7482 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2444385 T7482 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2444387 T7482 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 2444395 T7482 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 2445195 T7482 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2445201 T7482 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2445203 T7482 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2445211 T7482 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2445264 T7482 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2445265 T7482 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty2-1370557530630\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty2\
[junit4:junit4]   2> 2445265 T7482 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e774999
[junit4:junit4]   2> 2445265 T7482 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2445266 T7482 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty2\
[junit4:junit4]   2> 2445266 T7482 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty2\index/
[junit4:junit4]   2> 2445266 T7482 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2445267 T7482 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty2\index
[junit4:junit4]   2> 2445274 T7482 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@43273d57 lockFactory=org.apache.lucene.store.NativeFSLockFactory@75683d88),segFN=segments_1,generation=1}
[junit4:junit4]   2> 2445275 T7482 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 2445280 T7482 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2445280 T7482 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2445280 T7482 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2445282 T7482 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2445282 T7482 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2445282 T7482 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2445282 T7482 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2445285 T7482 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2445285 T7482 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2445299 T7482 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2445308 T7482 oass.SolrIndexSearcher.<init> Opening Searcher@7c1d0961 main
[junit4:junit4]   2> 2445308 T7482 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\jetty2\tlog
[junit4:junit4]   2> 2445310 T7482 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2445310 T7482 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2445319 T7483 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7c1d0961 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2445320 T7482 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 2445320 T7482 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2445971 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2445972 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50094__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50094/_/u"}
[junit4:junit4]   2> 2445972 T7446 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 2445972 T7446 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard=shard2
[junit4:junit4]   2> 2445983 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2445983 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2445985 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2445985 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2446621 T7482 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2446621 T7482 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50094/_/u collection:collection1 shard:shard2
[junit4:junit4]   2> 2446623 T7482 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 2446640 T7482 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2446647 T7482 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2446647 T7482 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2446647 T7482 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50094/_/u/collection1/
[junit4:junit4]   2> 2446648 T7482 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2446648 T7482 oasc.SyncStrategy.syncToMe http://127.0.0.1:50094/_/u/collection1/ has no replicas
[junit4:junit4]   2> 2446648 T7482 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50094/_/u/collection1/
[junit4:junit4]   2> 2446648 T7482 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 2447931 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2447957 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2447957 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2447957 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2447957 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2447976 T7482 oasc.ZkController.register We are http://127.0.0.1:50094/_/u/collection1/ and leader is http://127.0.0.1:50094/_/u/collection1/
[junit4:junit4]   2> 2447976 T7482 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50094/_/u
[junit4:junit4]   2> 2447976 T7482 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2447976 T7482 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 2447976 T7482 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2447981 T7482 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2447982 T7423 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2447983 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2447984 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2448510 T7423 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 2448516 T7423 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50103
[junit4:junit4]   2> 2448516 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2448518 T7423 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2448518 T7423 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517
[junit4:junit4]   2> 2448519 T7423 oasc.CoreContainer$Initializer.initialize looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517\solr.xml
[junit4:junit4]   2> 2448519 T7423 oasc.CoreContainer.<init> New CoreContainer 473670876
[junit4:junit4]   2> 2448520 T7423 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517\'
[junit4:junit4]   2> 2448520 T7423 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517\'
[junit4:junit4]   2> 2448661 T7423 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 2448663 T7423 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2448663 T7423 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 2448664 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2448664 T7423 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2448665 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2448665 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2448666 T7423 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2448666 T7423 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2448668 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 2448677 T7423 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 2448678 T7423 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50066/solr
[junit4:junit4]   2> 2448678 T7423 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 2448682 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2448702 T7495 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@42928969 name:ZooKeeperConnection Watcher:127.0.0.1:50066 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2448702 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2448707 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 2448716 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2448721 T7497 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@64b68251 name:ZooKeeperConnection Watcher:127.0.0.1:50066/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2448721 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2448731 T7423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2449908 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2449910 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50094__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50094/_/u"}
[junit4:junit4]   2> 2449927 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2449927 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2449927 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2449927 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2449927 T7497 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2450028 T7423 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50103__%2Fu
[junit4:junit4]   2> 2450032 T7423 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50103__%2Fu
[junit4:junit4]   2> 2450038 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2450038 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 2450039 T7497 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 2450041 T7497 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2450042 T7452 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 2450042 T7465 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 2450043 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2450043 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2450048 T7481 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 2450048 T7445 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 2450062 T7498 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517\collection1
[junit4:junit4]   2> 2450062 T7498 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2450063 T7498 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2450063 T7498 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2450067 T7498 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517\collection1\'
[junit4:junit4]   2> 2450070 T7498 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2450070 T7498 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517/collection1/lib/README' to classloader
[junit4:junit4]   2> 2450138 T7498 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2450222 T7498 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2450225 T7498 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 2450232 T7498 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 2451038 T7498 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2451043 T7498 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2451046 T7498 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2451053 T7498 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2451099 T7498 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2451100 T7498 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty3-1370557536517\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\
[junit4:junit4]   2> 2451100 T7498 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e774999
[junit4:junit4]   2> 2451100 T7498 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2451101 T7498 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\
[junit4:junit4]   2> 2451101 T7498 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\index/
[junit4:junit4]   2> 2451101 T7498 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\jetty3\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2451101 T7498 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\index
[junit4:junit4]   2> 2451106 T7498 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53eff293 lockFactory=org.apache.lucene.store.NativeFSLockFactory@23e0caef),segFN=segments_1,generation=1}
[junit4:junit4]   2> 2451106 T7498 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 2451112 T7498 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2451112 T7498 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2451114 T7498 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2451114 T7498 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2451115 T7498 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2451115 T7498 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2451115 T7498 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2451116 T7498 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2451116 T7498 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2451129 T7498 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2451139 T7498 oass.SolrIndexSearcher.<init> Opening Searcher@220dd0ac main
[junit4:junit4]   2> 2451140 T7498 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\jetty3\tlog
[junit4:junit4]   2> 2451141 T7498 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2451141 T7498 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2451149 T7499 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@220dd0ac main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2451152 T7498 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 2451152 T7498 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2451874 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2451875 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50103__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50103/_/u"}
[junit4:junit4]   2> 2451876 T7446 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 2451876 T7446 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard=shard1
[junit4:junit4]   2> 2451895 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2451897 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2451898 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2451898 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2451895 T7497 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2452416 T7498 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2452416 T7498 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50103/_/u collection:collection1 shard:shard1
[junit4:junit4]   2> 2452425 T7498 oasc.ZkController.register We are http://127.0.0.1:50103/_/u/collection1/ and leader is http://127.0.0.1:50085/_/u/collection1/
[junit4:junit4]   2> 2452426 T7498 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50103/_/u
[junit4:junit4]   2> 2452426 T7498 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 2452426 T7498 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C1976 name=collection1 org.apache.solr.core.SolrCore@8768f3f url=http://127.0.0.1:50103/_/u/collection1 node=127.0.0.1:50103__%2Fu C1976_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, collection=collection1, node_name=127.0.0.1:50103__%2Fu, base_url=http://127.0.0.1:50103/_/u}
[junit4:junit4]   2> 2452427 T7500 C1976 P50103 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 2452427 T7500 C1976 P50103 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 2452427 T7500 C1976 P50103 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 2452428 T7500 C1976 P50103 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2452428 T7498 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2452431 T7500 C1976 P50103 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2452432 T7423 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2452432 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2452433 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2452442 T7456 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: 4, state: recovering, checkLive: true, onlyIfLeader: true
[junit4:junit4]   2> 2452910 T7423 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4:junit4]   2> 2452916 T7423 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50113
[junit4:junit4]   2> 2452918 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2452919 T7423 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2452920 T7423 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964
[junit4:junit4]   2> 2452921 T7423 oasc.CoreContainer$Initializer.initialize looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964\solr.xml
[junit4:junit4]   2> 2452921 T7423 oasc.CoreContainer.<init> New CoreContainer 2023925171
[junit4:junit4]   2> 2452922 T7423 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964\'
[junit4:junit4]   2> 2452923 T7423 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964\'
[junit4:junit4]   2> 2453066 T7423 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 2453066 T7423 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2453066 T7423 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 2453067 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2453068 T7423 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2453068 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2453070 T7423 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2453070 T7423 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2453070 T7423 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2453071 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 2453079 T7423 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener
[junit4:junit4]   2> 2453081 T7423 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50066/solr
[junit4:junit4]   2> 2453081 T7423 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 2453083 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2453100 T7512 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@592bc510 name:ZooKeeperConnection Watcher:127.0.0.1:50066 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2453101 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2453107 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 2453107 T7425 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13f1b981862000b, likely client has closed socket
[junit4:junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4]   2> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 
[junit4:junit4]   2> 2453114 T7423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2453118 T7514 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@109100c4 name:ZooKeeperConnection Watcher:127.0.0.1:50066/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2453119 T7423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2453133 T7423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2453682 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2453683 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50103__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50103/_/u"}
[junit4:junit4]   2> 2453697 T7497 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2453698 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2453698 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2453699 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2453697 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2453697 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2454303 T7423 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50113__%2Fu
[junit4:junit4]   2> 2454306 T7423 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50113__%2Fu
[junit4:junit4]   2> 2454311 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2454312 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 2454313 T7497 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 2454313 T7497 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2454314 T7514 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 2454314 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2454314 T7465 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 2454315 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2454313 T7452 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 2454317 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2454320 T7481 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 2454321 T7445 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 2454330 T7515 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964\collection1
[junit4:junit4]   2> 2454330 T7515 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2454331 T7515 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2454331 T7515 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2454334 T7515 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964\collection1\'
[junit4:junit4]   2> 2454337 T7515 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2454337 T7515 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964/collection1/lib/README' to classloader
[junit4:junit4]   2> 2454407 T7515 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2454475 T7515 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2454479 T7515 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4:junit4]   2> 2454486 T7515 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4:junit4]   2> 2454766 T7456 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: 4, state: recovering, checkLive: true, onlyIfLeader: true for: 2 seconds.
[junit4:junit4]   2> 2454767 T7456 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={coreNodeName=4&state=recovering&nodeName=127.0.0.1:50103__%252Fu&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=2325 
[junit4:junit4]   2> 2455164 T7515 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2455169 T7515 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2455172 T7515 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2455178 T7515 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2455220 T7515 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2455221 T7515 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-jetty4-1370557540964\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\
[junit4:junit4]   2> 2455221 T7515 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e774999
[junit4:junit4]   2> 2455221 T7515 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2455222 T7515 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\
[junit4:junit4]   2> 2455222 T7515 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\index/
[junit4:junit4]   2> 2455222 T7515 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\jetty4\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2455222 T7515 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\index
[junit4:junit4]   2> 2455227 T7515 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@27f3ed1e lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fb66cf5),segFN=segments_1,generation=1}
[junit4:junit4]   2> 2455227 T7515 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 2455232 T7515 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2455232 T7515 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2455233 T7515 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2455233 T7515 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2455235 T7515 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2455235 T7515 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2455235 T7515 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2455236 T7515 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2455236 T7515 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2455248 T7515 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2455256 T7515 oass.SolrIndexSearcher.<init> Opening Searcher@23f70428 main
[junit4:junit4]   2> 2455257 T7515 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958\jetty4\tlog
[junit4:junit4]   2> 2455258 T7515 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2455258 T7515 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2455264 T7516 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23f70428 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2455265 T7515 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 2455265 T7515 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2455456 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2455457 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50113__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50113/_/u"}
[junit4:junit4]   2> 2455457 T7446 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 2455457 T7446 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard=shard2
[junit4:junit4]   2> 2455467 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2455467 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2455468 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2455467 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2455467 T7497 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2455467 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2456438 T7515 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2456438 T7515 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50113/_/u collection:collection1 shard:shard2
[junit4:junit4]   2> 2456445 T7515 oasc.ZkController.register We are http://127.0.0.1:50113/_/u/collection1/ and leader is http://127.0.0.1:50094/_/u/collection1/
[junit4:junit4]   2> 2456445 T7515 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50113/_/u
[junit4:junit4]   2> 2456445 T7515 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 2456445 T7515 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C1977 name=collection1 org.apache.solr.core.SolrCore@3b8765f url=http://127.0.0.1:50113/_/u/collection1 node=127.0.0.1:50113__%2Fu C1977_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, collection=collection1, node_name=127.0.0.1:50113__%2Fu, base_url=http://127.0.0.1:50113/_/u}
[junit4:junit4]   2> 2456446 T7517 C1977 P50113 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 2456448 T7517 C1977 P50113 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 2456448 T7517 C1977 P50113 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 2456448 T7517 C1977 P50113 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2456448 T7515 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2456451 T7517 C1977 P50113 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2456452 T7423 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2456453 T7423 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2456453 T7423 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2456461 T7423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2456462 T7475 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: 5, state: recovering, checkLive: true, onlyIfLeader: true
[junit4:junit4]   2> 2456463 T7423 oasc.AbstractFullDistribZkTestBase.waitForThingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt
[junit4:junit4]   2> 2456463 T7423 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):15
[junit4:junit4]   2> 2456466 T7423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C1976_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:50103__%2Fu, base_url=http://127.0.0.1:50103/_/u}
[junit4:junit4]   2> 2457092 T7500 C1976 P50103 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:50085/_/u/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 2457092 T7500 C1976 P50103 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:50103/_/u START replicas=[http://127.0.0.1:50085/_/u/collection1/] nUpdates=100
[junit4:junit4]   2> 2457094 T7500 C1976 P50103 oasu.PeerSync.sync WARN no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 2457095 T7500 C1976 P50103 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 2457095 T7500 C1976 P50103 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 2457097 T7500 C1976 P50103 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 2457097 T7500 C1976 P50103 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 2457097 T7500 C1976 P50103 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:50085/_/u/collection1/. core=collection1
[junit4:junit4]   2> 2457098 T7500 C1976 P50103 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C1978 name=collection1 org.apache.solr.core.SolrCore@39fd2be5 url=http://127.0.0.1:50085/_/u/collection1 node=127.0.0.1:50085__%2Fu C1978_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:50085__%2Fu, base_url=http://127.0.0.1:50085/_/u, leader=true}
[junit4:junit4]   2> 2457119 T7457 C1978 P50085 oasc.SolrCore.execute [collection1] webapp=/_/u path=/get params={getVersions=100&distrib=false&wt=javabin&qt=/get&version=2} status=0 QTime=1 
[junit4:junit4]   2> 2457128 T7458 C1978 P50085 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2457136 T7458 C1978 P50085 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53bc97e lockFactory=org.apache.lucene.store.NativeFSLockFactory@6814cd77),segFN=segments_1,generation=1}
[junit4:junit4]   2> 2457139 T7458 C1978 P50085 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 2457139 T7458 C1978 P50085 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53bc97e lockFactory=org.apache.lucene.store.NativeFSLockFactory@6814cd77),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53bc97e lockFactory=org.apache.lucene.store.NativeFSLockFactory@6814cd77),segFN=segments_2,generation=2}
[junit4:junit4]   2> 2457140 T7458 C1978 P50085 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 2457140 T7458 C1978 P50085 oass.SolrIndexSearcher.<init> Opening Searcher@1e8588a4 realtime
[junit4:junit4]   2> 2457140 T7458 C1978 P50085 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2457140 T7458 C1978 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 2457143 T7500 C1976 P50103 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 2457143 T7500 C1976 P50103 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 2457149 T7460 C1978 P50085 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 2457150 T7460 C1978 P50085 oasc.SolrCore.execute [collection1] webapp=/_/u path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 2457151 T7500 C1976 P50103 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 2457151 T7500 C1976 P50103 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 2457151 T7500 C1976 P50103 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 2457156 T7456 C1978 P50085 oasc.SolrCore.execute [collection1] webapp=/_/u path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=0 
[junit4:junit4]   2> 2457157 T7500 C1976 P50103 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 2457158 T7500 C1976 P50103 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\index.20130607012545680
[junit4:junit4]   2> 2457158 T7500 C1976 P50103 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@61709380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@75a748a7) fullCopy=false
[junit4:junit4]   2> 2457160 T7461 C1978 P50085 oasc.SolrCore.execute [collection1] webapp=/_/u path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 2457162 T7500 C1976 P50103 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 2457162 T7500 C1976 P50103 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 2457162 T7500 C1976 P50103 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 2457167 T7500 C1976 P50103 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53eff293 lockFactory=org.apache.lucene.store.NativeFSLockFactory@23e0caef),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53eff293 lockFactory=org.apache.lucene.store.NativeFSLockFactory@23e0caef),segFN=segments_2,generation=2}
[junit4:junit4]   2> 2457168 T7500 C1976 P50103 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 2457168 T7500 C1976 P50103 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 2457168 T7500 C1976 P50103 oass.SolrIndexSearcher.<init> Opening Searcher@3f5a49f4 main
[junit4:junit4]   2> 2457169 T7499 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3f5a49f4 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 2457170 T7500 C1976 P50103 oasc.CachingDirectoryFactory.closeCacheValue looking to close .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\index.20130607012545680 [CachedDir<<refCount=0;path=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\index.20130607012545680;done=true>>]
[junit4:junit4]   2> 2457170 T7500 C1976 P50103 oasc.CachingDirectoryFactory.close Closing directory: .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\index.20130607012545680
[junit4:junit4]   2> 2457170 T7500 C1976 P50103 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty3\index.20130607012545680
[junit4:junit4]   2> 2457170 T7500 C1976 P50103 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 2457170 T7500 C1976 P50103 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 2457171 T7500 C1976 P50103 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 2457171 T7500 C1976 P50103 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2457182 T7500 C1976 P50103 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 2457226 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2457228 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50113__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50113/_/u"}
[junit4:junit4]   2> 2457248 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50103__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50103/_/u"}
[junit4:junit4]   2> 2457270 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2457272 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2457274 T7497 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2457274 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2457275 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2457272 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2457625 T7475 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: 5, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4:junit4]   2> 2457625 T7475 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={coreNodeName=5&state=recovering&nodeName=127.0.0.1:50113__%252Fu&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=1163 
[junit4:junit4]   2> 2457629 T7423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2458793 T7423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C1977_STATE=coll:collection1 core:collection1 props:{shard=shard2, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:50113__%2Fu, base_url=http://127.0.0.1:50113/_/u}
[junit4:junit4]   2> 2459950 T7517 C1977 P50113 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:50094/_/u/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 2459952 T7517 C1977 P50113 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:50113/_/u START replicas=[http://127.0.0.1:50094/_/u/collection1/] nUpdates=100
[junit4:junit4]   2> 2459954 T7517 C1977 P50113 oasu.PeerSync.sync WARN no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 2459954 T7517 C1977 P50113 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 2459955 T7517 C1977 P50113 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 2459955 T7517 C1977 P50113 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 2459956 T7517 C1977 P50113 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 2459956 T7517 C1977 P50113 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:50094/_/u/collection1/. core=collection1
[junit4:junit4]   2> 2459956 T7517 C1977 P50113 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2459971 T7423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C1979 name=collection1 org.apache.solr.core.SolrCore@34b0f3a6 url=http://127.0.0.1:50094/_/u/collection1 node=127.0.0.1:50094__%2Fu C1979_STATE=coll:collection1 core:collection1 props:{shard=shard2, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:50094__%2Fu, base_url=http://127.0.0.1:50094/_/u, leader=true}
[junit4:junit4]   2> 2459973 T7472 C1979 P50094 oasc.SolrCore.execute [collection1] webapp=/_/u path=/get params={getVersions=100&distrib=false&wt=javabin&qt=/get&version=2} status=0 QTime=1 
[junit4:junit4]   2> 2459984 T7473 C1979 P50094 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2459989 T7473 C1979 P50094 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@43273d57 lockFactory=org.apache.lucene.store.NativeFSLockFactory@75683d88),segFN=segments_1,generation=1}
[junit4:junit4]   2> 2459990 T7473 C1979 P50094 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 2459990 T7473 C1979 P50094 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@43273d57 lockFactory=org.apache.lucene.store.NativeFSLockFactory@75683d88),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@43273d57 lockFactory=org.apache.lucene.store.NativeFSLockFactory@75683d88),segFN=segments_2,generation=2}
[junit4:junit4]   2> 2459990 T7473 C1979 P50094 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 2459991 T7473 C1979 P50094 oass.SolrIndexSearcher.<init> Opening Searcher@324ae924 realtime
[junit4:junit4]   2> 2459991 T7473 C1979 P50094 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2459991 T7473 C1979 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 2459993 T7517 C1977 P50113 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 2459993 T7517 C1977 P50113 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 2459997 T7476 C1979 P50094 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 2459997 T7476 C1979 P50094 oasc.SolrCore.execute [collection1] webapp=/_/u path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 2459998 T7517 C1977 P50113 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 2459998 T7517 C1977 P50113 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 2459998 T7517 C1977 P50113 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 2460001 T7477 C1979 P50094 oasc.SolrCore.execute [collection1] webapp=/_/u path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=0 
[junit4:junit4]   2> 2460003 T7517 C1977 P50113 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 2460004 T7517 C1977 P50113 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\index.20130607012548526
[junit4:junit4]   2> 2460004 T7517 C1977 P50113 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@6498605a lockFactory=org.apache.lucene.store.NativeFSLockFactory@123ec63f) fullCopy=false
[junit4:junit4]   2> 2460007 T7475 C1979 P50094 oasc.SolrCore.execute [collection1] webapp=/_/u path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 2460008 T7517 C1977 P50113 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 2460009 T7517 C1977 P50113 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 2460009 T7517 C1977 P50113 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 2460012 T7517 C1977 P50113 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@27f3ed1e lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fb66cf5),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@27f3ed1e lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fb66cf5),segFN=segments_2,generation=2}
[junit4:junit4]   2> 2460013 T7517 C1977 P50113 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 2460013 T7517 C1977 P50113 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 2460013 T7517 C1977 P50113 oass.SolrIndexSearcher.<init> Opening Searcher@6e38631e main
[junit4:junit4]   2> 2460015 T7516 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e38631e main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 2460015 T7517 C1977 P50113 oasc.CachingDirectoryFactory.closeCacheValue looking to close .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\index.20130607012548526 [CachedDir<<refCount=0;path=.\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\index.20130607012548526;done=true>>]
[junit4:junit4]   2> 2460015 T7517 C1977 P50113 oasc.CachingDirectoryFactory.close Closing directory: .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\index.20130607012548526
[junit4:junit4]   2> 2460016 T7517 C1977 P50113 oasc.CachingDirectoryFactory.closeCacheValue Removing directory before core close: .\org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1370557519958/jetty4\index.20130607012548526
[junit4:junit4]   2> 2460016 T7517 C1977 P50113 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 2460016 T7517 C1977 P50113 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 2460016 T7517 C1977 P50113 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 2460016 T7517 C1977 P50113 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2460026 T7517 C1977 P50113 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 2460791 T7446 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2460795 T7446 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard_state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard_range":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50113__%2Fu",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50113/_/u"}
[junit4:junit4]   2> 2460817 T7481 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2460817 T7465 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2460818 T7497 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2460820 T7445 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2460821 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2460822 T7452 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 2461140 T7423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2461143 T7423 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C1980 name=collection1 org.apache.solr.core.SolrCore@303e276f url=http://127.0.0.1:50073/_/u/collection1 node=127.0.0.1:50073__%2Fu C1980_STATE=coll:control_collection core:collection1 props:{shard=shard1, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:50073__%2Fu, base_url=http://127.0.0.1:50073/_/u, leader=true}
[junit4:junit4]   2> 2461166 T7441 C1980 P50073 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2461174 T7441 C1980 P50073 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@394d82b7 lockFactory=org.apache.lucene.store.NativeFSLockFactory@62a41d5a),segFN=segments_1,generation=1}
[junit4:junit4]   2> 2461177 T7441 C1980 P50073 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4:junit4]   2> 2461178 T7441 C1980 P50073 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@394d82b7 lockFactory=org.apache.lucene.store.NativeFSLockFactory@62a41d5a),segFN=segments_1,generation=1}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@394d82b7 lockFactory=org.apache.lucene.store.NativeFSLockFactory@62a41d5a),segFN=segments_2,generation=2}
[junit4:junit4]   2> 2461179 T7441 C1980 P50073 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4:junit4]   2> 2461179 T7441 C1980 P50073 oass.SolrIndexSearcher.<init> Opening Searcher@47bbfea5 main
[junit4:junit4]   2> 2461180 T7441 C1980 P50073 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2461180 T7449 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@47bbfea5 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 2461182 T7441 C1980 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 17
[junit4:junit4]   2> 2461191 T7474 C1979 P50094 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2461191 T7474 C1979 P50094 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@43273d57 lockFactory=org.apache.lucene.store.NativeFSLockFactory@75683d88),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@43273d57 lockFactory=org.apache.lucene.store.NativeFSLockFactory@75683d88),segFN=segments_3,generation=3}
[junit4:junit4]   2> 2461192 T7474 C1979 P50094 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4]   2> 2461192 T7474 C1979 P50094 oass.SolrIndexSearcher.<init> Opening Searcher@28774e8d main
[junit4:junit4]   2> 2461193 T7474 C1979 P50094 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2461193 T7483 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28774e8d main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> ASYNC  NEW_CORE C1981 name=collection1 org.apache.solr.core.SolrCore@34b0f3a6 url=http://127.0.0.1:50094/_/u/collection1 node=127.0.0.1:50094__%2Fu C1981_STATE=coll:collection1 core:collection1 props:{shard=shard2, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:50094__%2Fu, base_url=http://127.0.0.1:50094/_/u, leader=true}
[junit4:junit4]   2> 2461239 T7474 C1981 P50094 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:50085/_/u/collection1/, StdNode: http://127.0.0.1:50103/_/u/collection1/, StdNode: http://127.0.0.1:50113/_/u/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2> ASYNC  NEW_CORE C1982 name=collection1 org.apache.solr.core.SolrCore@39fd2be5 url=http://127.0.0.1:50085/_/u/collection1 node=127.0.0.1:50085__%2Fu C1982_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:50085__%2Fu, base_url=http://127.0.0.1:50085/_/u, leader=true}
[junit4:junit4]   2> 2461246 T7459 C1982 P50085 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> ASYNC  NEW_CORE C1983 name=collection1 org.apache.solr.core.SolrCore@8768f3f url=http://127.0.0.1:50103/_/u/collection1 node=127.0.0.1:50103__%2Fu C1983_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:50103__%2Fu, base_url=http://127.0.0.1:50103/_/u}
[junit4:junit4]   2> 2461254 T7490 C1983 P50103 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> ASYNC  NEW_CORE C1984 name=collection1 org.apache.solr.core.SolrCore@3b8765f url=http://127.0.0.1:50113/_/u/collection1 node=127.0.0.1:50113__%2Fu C1984_STATE=coll:collection1 core:collection1 props:{shard=shard2, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:50113__%2Fu, base_url=http://127.0.0.1:50113/_/u}
[junit4:junit4]   2> 2461254 T7510 C1984 P50113 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2461254 T7459 C1982 P50085 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53bc97e lockFactory=org.apache.lucene.store.NativeFSLockFactory@6814cd77),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53bc97e lockFactory=org.apache.lucene.store.NativeFSLockFactory@6814cd77),segFN=segments_3,generation=3}
[junit4:junit4]   2> 2461256 T7459 C1982 P50085 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4]   2> 2461254 T7490 C1983 P50103 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53eff293 lockFactory=org.apache.lucene.store.NativeFSLockFactory@23e0caef),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@53eff293 lockFactory=org.apache.lucene.store.NativeFSLockFactory@23e0caef),segFN=segments_3,generation=3}
[junit4:junit4]   2> 2461256 T7490 C1983 P50103 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4]   2> 2461256 T7459 C1982 P50085 oass.SolrIndexSearcher.<init> Opening Searcher@7401f626 main
[junit4:junit4]   2> 2461255 T7510 C1984 P50113 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@27f3ed1e lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fb66cf5),segFN=segments_2,generation=2}
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@27f3ed1e lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fb66cf5),segFN=segments_3,generation=3}
[junit4:junit4]   2> 2461259 T7510 C1984 P50113 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4:junit4]   2> 2461259 T7459 C1982 P50085 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2461259 T7490 C1983 P50103 oass.SolrIndexSearcher.<init> Opening Searcher@33dd1e48 main
[junit4:junit4]   2> 2461260 T7510 C1984 P50113 oass.SolrIndexSearcher.<init> Opening Searcher@536f4de8 main
[junit4:junit4]   2> 2461261 T7510 C1984 P50113 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2461259 T7467 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7401f626 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 2461261 T7499 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@33dd1e48 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 2461261 T7490 C1983 P50103 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2461263 T7490 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 2461262 T7459 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4]   2> 2461262 T7516 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@536f4de8 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 2461264 T7510 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={waitSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 2461265 T7474 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 74
[junit4:junit4]   2> 2461267 T7423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2461271 T7460 C1982 P50085 oasc.SolrCore.execute [collection1] webapp=/_/u path=/select params={tests=checkShardConsistency&q=*:*&distrib=false&wt=javabin&version=2&rows=0} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2461273 T7491 C1983 P50103 oasc.SolrCore.execute [collection1] webapp=/_/u path=/select params={tests=checkShardConsistency&q=*:*&distrib=false&wt=javabin&version=2&rows=0} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2461276 T7476 C1981 P50094 oasc.SolrCore.execute [collection1] webapp=/_/u path=/select params={tests=checkShardConsistency&q=*:*&distrib=false&wt=javabin&version=2&rows=0} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2461278 T7506 C1984 P50113 oasc.SolrCore.execute [collection1] webapp=/_/u path=/select params={tests=checkShardConsistency&q=*:*&distrib=false&wt=javabin&version=2&rows=0} hits=0 status=0 QTime=0 
[junit4:junit4]   2> ASYNC  NEW_CORE C1985 name=collection1 org.apache.solr.core.SolrCore@303e276f url=http://127.0.0.1:50073/_/u/collection1 node=127.0.0.1:50073__%2Fu C1985_STATE=coll:control_collection core:collection1 props:{shard=shard1, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:50073__%2Fu, base_url=http://127.0.0.1:50073/_/u, leader=true}
[junit4:junit4]   2> 2463606 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437133755779121152)} 0 2
[junit4:junit4]   2> 2463619 T7491 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={update.distrib=FROMLEADER&_version_=-1437133755785412608&update.from=http://127.0.0.1:50085/_/u/collection1/&wt=javabin&version=2} {deleteByQuery=*:* (-1437133755785412608)} 0 2
[junit4:junit4]   2> 2463626 T7506 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={update.distrib=FROMLEADER&_version_=-1437133755793801216&update.from=http://127.0.0.1:50094/_/u/collection1/&wt=javabin&version=2} {deleteByQuery=*:* (-1437133755793801216)} 0 3
[junit4:junit4]   2> 2463627 T7476 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} {deleteByQuery=*:* (-1437133755793801216)} 0 10
[junit4:junit4]   2> 2463627 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437133755785412608)} 0 17
[junit4:junit4]   2> 2463631 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[0 (1437133755806384128)]} 0 1
[junit4:junit4]   2> 2463638 T7492 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[0 (1437133755810578432)]} 0 1
[junit4:junit4]   2> 2463639 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[0 (1437133755810578432)]} 0 5
[junit4:junit4]   2> 2463643 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[1 (1437133755820015616)]} 0 0
[junit4:junit4]   2> 2463649 T7493 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[1 (1437133755823161344)]} 0 0
[junit4:junit4]   2> 2463652 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[1 (1437133755823161344)]} 0 6
[junit4:junit4]   2> 2463656 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[2 (1437133755832598528)]} 0 1
[junit4:junit4]   2> 2463667 T7507 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[2 (1437133755838889984)]} 0 2
[junit4:junit4]   2> 2463668 T7477 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[2 (1437133755838889984)]} 0 7
[junit4:junit4]   2> 2463668 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[2]} 0 9
[junit4:junit4]   2> 2463672 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[3 (1437133755849375744)]} 0 1
[junit4:junit4]   2> 2463681 T7508 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[3 (1437133755855667200)]} 0 0
[junit4:junit4]   2> 2463682 T7475 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[3 (1437133755855667200)]} 0 5
[junit4:junit4]   2> 2463682 T7458 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[3]} 0 8
[junit4:junit4]   2> 2463686 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[4 (1437133755865104384)]} 0 0
[junit4:junit4]   2> 2463696 T7488 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[4 (1437133755872444416)]} 0 0
[junit4:junit4]   2> 2463698 T7459 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[4 (1437133755872444416)]} 0 5
[junit4:junit4]   2> 2463698 T7472 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[4]} 0 9
[junit4:junit4]   2> 2463702 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[5 (1437133755881881600)]} 0 0
[junit4:junit4]   2> 2463712 T7509 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[5 (1437133755887124480)]} 0 0
[junit4:junit4]   2> 2463713 T7473 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[5 (1437133755887124480)]} 0 6
[junit4:junit4]   2> 2463714 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[5]} 0 9
[junit4:junit4]   2> 2463719 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[6 (1437133755898658816)]} 0 1
[junit4:junit4]   2> 2463728 T7505 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[6 (1437133755901804544)]} 0 2
[junit4:junit4]   2> 2463740 T7474 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[6 (1437133755901804544)]} 0 19
[junit4:junit4]   2> 2463745 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[7 (1437133755925921792)]} 0 1
[junit4:junit4]   2> 2463753 T7510 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[7 (1437133755931164672)]} 0 0
[junit4:junit4]   2> 2463754 T7476 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[7 (1437133755931164672)]} 0 5
[junit4:junit4]   2> 2463755 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[7]} 0 8
[junit4:junit4]   2> 2463758 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[8 (1437133755940601856)]} 0 0
[junit4:junit4]   2> 2463765 T7489 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[8 (1437133755943747584)]} 0 1
[junit4:junit4]   2> 2463766 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[8 (1437133755943747584)]} 0 5
[junit4:junit4]   2> 2463768 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[9 (1437133755951087616)]} 0 0
[junit4:junit4]   2> 2463777 T7506 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[9 (1437133755957379072)]} 0 0
[junit4:junit4]   2> 2463778 T7477 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[9 (1437133755957379072)]} 0 4
[junit4:junit4]   2> 2463779 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[9]} 0 8
[junit4:junit4]   2> 2463783 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[10 (1437133755965767680)]} 0 1
[junit4:junit4]   2> 2463789 T7490 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10 (1437133755968913408)]} 0 1
[junit4:junit4]   2> 2463790 T7458 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[10 (1437133755968913408)]} 0 5
[junit4:junit4]   2> 2463794 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[11 (1437133755977302016)]} 0 1
[junit4:junit4]   2> 2463801 T7491 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[11 (1437133755981496320)]} 0 0
[junit4:junit4]   2> 2463802 T7459 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[11 (1437133755981496320)]} 0 5
[junit4:junit4]   2> 2463806 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[12 (1437133755989884928)]} 0 1
[junit4:junit4]   2> 2463811 T7492 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[12 (1437133755993030656)]} 0 0
[junit4:junit4]   2> 2463812 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[12 (1437133755993030656)]} 0 4
[junit4:junit4]   2> 2463816 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[13 (1437133756000370688)]} 0 1
[junit4:junit4]   2> 2463823 T7493 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[13 (1437133756005613568)]} 0 0
[junit4:junit4]   2> 2463824 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[13 (1437133756005613568)]} 0 4
[junit4:junit4]   2> 2463825 T7475 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[13]} 0 7
[junit4:junit4]   2> 2463828 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[14 (1437133756012953600)]} 0 1
[junit4:junit4]   2> 2463833 T7488 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[14 (1437133756016099328)]} 0 0
[junit4:junit4]   2> 2463835 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[14 (1437133756016099328)]} 0 5
[junit4:junit4]   2> 2463838 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[15 (1437133756024487936)]} 0 0
[junit4:junit4]   2> 2463847 T7489 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[15 (1437133756030779392)]} 0 0
[junit4:junit4]   2> 2463849 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[15 (1437133756030779392)]} 0 5
[junit4:junit4]   2> 2463850 T7472 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[15]} 0 8
[junit4:junit4]   2> 2463854 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[16 (1437133756040216576)]} 0 1
[junit4:junit4]   2> 2463860 T7490 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[16 (1437133756045459456)]} 0 0
[junit4:junit4]   2> 2463861 T7458 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[16 (1437133756045459456)]} 0 3
[junit4:junit4]   2> 2463862 T7473 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[16]} 0 6
[junit4:junit4]   2> 2463864 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[17 (1437133756051750912)]} 0 0
[junit4:junit4]   2> 2463870 T7507 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[17 (1437133756054896640)]} 0 0
[junit4:junit4]   2> 2463872 T7474 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[17 (1437133756054896640)]} 0 5
[junit4:junit4]   2> 2463875 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[18 (1437133756063285248)]} 0 0
[junit4:junit4]   2> 2463882 T7508 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[18 (1437133756066430976)]} 0 1
[junit4:junit4]   2> 2463883 T7476 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[18 (1437133756066430976)]} 0 5
[junit4:junit4]   2> 2463885 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[19 (1437133756073771008)]} 0 0
[junit4:junit4]   2> 2463891 T7509 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[19 (1437133756076916736)]} 0 0
[junit4:junit4]   2> 2463892 T7477 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[19 (1437133756076916736)]} 0 4
[junit4:junit4]   2> 2463895 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[20 (1437133756084256768)]} 0 0
[junit4:junit4]   2> 2463904 T7491 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[20 (1437133756090548224)]} 0 0
[junit4:junit4]   2> 2463906 T7459 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[20 (1437133756090548224)]} 0 5
[junit4:junit4]   2> 2463907 T7475 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[20]} 0 8
[junit4:junit4]   2> 2463909 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[21 (1437133756098936832)]} 0 0
[junit4:junit4]   2> 2463914 T7505 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[21 (1437133756102082560)]} 0 0
[junit4:junit4]   2> 2463915 T7472 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[21 (1437133756102082560)]} 0 3
[junit4:junit4]   2> 2463918 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[22 (1437133756108374016)]} 0 0
[junit4:junit4]   2> 2463926 T7492 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[22 (1437133756113616896)]} 0 0
[junit4:junit4]   2> 2463927 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[22 (1437133756113616896)]} 0 4
[junit4:junit4]   2> 2463928 T7473 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[22]} 0 7
[junit4:junit4]   2> 2463931 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[23 (1437133756122005504)]} 0 0
[junit4:junit4]   2> 2463937 T7510 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[23 (1437133756125151232)]} 0 0
[junit4:junit4]   2> 2463939 T7474 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[23 (1437133756125151232)]} 0 5
[junit4:junit4]   2> 2463941 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[24 (1437133756132491264)]} 0 0
[junit4:junit4]   2> 2463949 T7493 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[24 (1437133756138782720)]} 0 0
[junit4:junit4]   2> 2463951 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[24 (1437133756138782720)]} 0 4
[junit4:junit4]   2> 2463951 T7476 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[24]} 0 6
[junit4:junit4]   2> 2463955 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[25 (1437133756146122752)]} 0 1
[junit4:junit4]   2> 2463960 T7506 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[25 (1437133756149268480)]} 0 0
[junit4:junit4]   2> 2463961 T7477 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[25 (1437133756149268480)]} 0 4
[junit4:junit4]   2> 2463965 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[26 (1437133756156608512)]} 0 1
[junit4:junit4]   2> 2463974 T7507 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[26 (1437133756163948544)]} 0 0
[junit4:junit4]   2> 2463975 T7475 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[26 (1437133756163948544)]} 0 4
[junit4:junit4]   2> 2463976 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[26]} 0 8
[junit4:junit4]   2> 2463979 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[27 (1437133756172337152)]} 0 0
[junit4:junit4]   2> 2463987 T7488 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[27 (1437133756177580032)]} 0 0
[junit4:junit4]   2> 2463988 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[27 (1437133756177580032)]} 0 4
[junit4:junit4]   2> 2463989 T7472 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[27]} 0 7
[junit4:junit4]   2> 2463993 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[28 (1437133756187017216)]} 0 0
[junit4:junit4]   2> 2464001 T7489 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[28 (1437133756192260096)]} 0 0
[junit4:junit4]   2> 2464002 T7458 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[28 (1437133756192260096)]} 0 4
[junit4:junit4]   2> 2464003 T7473 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[28]} 0 7
[junit4:junit4]   2> 2464005 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[29 (1437133756199600128)]} 0 0
[junit4:junit4]   2> 2464013 T7508 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[29 (1437133756204843008)]} 0 0
[junit4:junit4]   2> 2464014 T7476 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[29 (1437133756204843008)]} 0 4
[junit4:junit4]   2> 2464015 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[29]} 0 7
[junit4:junit4]   2> 2464018 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[30 (1437133756213231616)]} 0 0
[junit4:junit4]   2> 2464024 T7505 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[30 (1437133756216377344)]} 0 0
[junit4:junit4]   2> 2464025 T7477 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[30 (1437133756216377344)]} 0 4
[junit4:junit4]   2> 2464029 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[31 (1437133756223717376)]} 0 1
[junit4:junit4]   2> 2464037 T7510 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[31 (1437133756228960256)]} 0 1
[junit4:junit4]   2> 2464037 T7475 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[31 (1437133756228960256)]} 0 4
[junit4:junit4]   2> 2464038 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[31]} 0 7
[junit4:junit4]   2> 2464043 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[32 (1437133756239446016)]} 0 0
[junit4:junit4]   2> 2464050 T7491 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[32 (1437133756244688896)]} 0 0
[junit4:junit4]   2> 2464051 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[32 (1437133756244688896)]} 0 3
[junit4:junit4]   2> 2464052 T7472 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[32]} 0 6
[junit4:junit4]   2> 2464054 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[33 (1437133756250980352)]} 0 0
[junit4:junit4]   2> 2464063 T7506 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[33 (1437133756257271808)]} 0 0
[junit4:junit4]   2> 2464064 T7473 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[33 (1437133756257271808)]} 0 4
[junit4:junit4]   2> 2464065 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[33]} 0 7
[junit4:junit4]   2> 2464067 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[34 (1437133756264611840)]} 0 0
[junit4:junit4]   2> 2464073 T7492 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[34 (1437133756267757568)]} 0 0
[junit4:junit4]   2> 2464074 T7459 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[34 (1437133756267757568)]} 0 4
[junit4:junit4]   2> 2464076 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[35 (1437133756274049024)]} 0 0
[junit4:junit4]   2> 2464085 T7493 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[35 (1437133756280340480)]} 0 0
[junit4:junit4]   2> 2464086 T7458 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[35 (1437133756280340480)]} 0 4
[junit4:junit4]   2> 2464087 T7474 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[35]} 0 7
[junit4:junit4]   2> 2464090 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[36 (1437133756288729088)]} 0 0
[junit4:junit4]   2> 2464096 T7488 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[36 (1437133756291874816)]} 0 0
[junit4:junit4]   2> 2464098 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[36 (1437133756291874816)]} 0 5
[junit4:junit4]   2> 2464100 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[37 (1437133756299214848)]} 0 0
[junit4:junit4]   2> 2464109 T7507 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[37 (1437133756304457728)]} 0 1
[junit4:junit4]   2> 2464109 T7476 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[37 (1437133756304457728)]} 0 4
[junit4:junit4]   2> 2464110 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[37]} 0 7
[junit4:junit4]   2> 2464114 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[38 (1437133756312846336)]} 0 1
[junit4:junit4]   2> 2464122 T7489 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[38 (1437133756318089216)]} 0 0
[junit4:junit4]   2> 2464123 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[38 (1437133756318089216)]} 0 5
[junit4:junit4]   2> 2464124 T7477 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[38]} 0 8
[junit4:junit4]   2> 2464127 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[39 (1437133756327526400)]} 0 0
[junit4:junit4]   2> 2464133 T7509 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[39 (1437133756330672128)]} 0 0
[junit4:junit4]   2> 2464134 T7475 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[39 (1437133756330672128)]} 0 4
[junit4:junit4]   2> 2464138 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[40 (1437133756339060736)]} 0 0
[junit4:junit4]   2> 2464148 T7490 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[40 (1437133756346400768)]} 0 0
[junit4:junit4]   2> 2464149 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[40 (1437133756346400768)]} 0 4
[junit4:junit4]   2> 2464150 T7472 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[40]} 0 8
[junit4:junit4]   2> 2464152 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[41 (1437133756353740800)]} 0 0
[junit4:junit4]   2> 2464158 T7491 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[41 (1437133756356886528)]} 0 0
[junit4:junit4]   2> 2464160 T7459 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[41 (1437133756356886528)]} 0 5
[junit4:junit4]   2> 2464162 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[42 (1437133756364226560)]} 0 0
[junit4:junit4]   2> 2464170 T7492 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[42 (1437133756369469440)]} 0 0
[junit4:junit4]   2> 2464171 T7458 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[42 (1437133756369469440)]} 0 4
[junit4:junit4]   2> 2464172 T7473 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[42]} 0 7
[junit4:junit4]   2> 2464174 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[43 (1437133756376809472)]} 0 0
[junit4:junit4]   2> 2464180 T7493 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[43 (1437133756379955200)]} 0 0
[junit4:junit4]   2> 2464182 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[43 (1437133756379955200)]} 0 5
[junit4:junit4]   2> 2464184 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[44 (1437133756387295232)]} 0 0
[junit4:junit4]   2> 2464192 T7488 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[44 (1437133756393586688)]} 0 0
[junit4:junit4]   2> 2464193 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[44 (1437133756393586688)]} 0 3
[junit4:junit4]   2> 2464194 T7474 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[44]} 0 6
[junit4:junit4]   2> 2464196 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[45 (1437133756399878144)]} 0 0
[junit4:junit4]   2> 2464205 T7508 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[45 (1437133756405121024)]} 0 1
[junit4:junit4]   2> 2464205 T7476 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[45 (1437133756405121024)]} 0 4
[junit4:junit4]   2> 2464206 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[45]} 0 7
[junit4:junit4]   2> 2464208 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[46 (1437133756412461056)]} 0 0
[junit4:junit4]   2> 2464214 T7489 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[46 (1437133756415606784)]} 0 0
[junit4:junit4]   2> 2464216 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[46 (1437133756415606784)]} 0 5
[junit4:junit4]   2> 2464219 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[47 (1437133756422946816)]} 0 1
[junit4:junit4]   2> 2464227 T7490 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[47 (1437133756429238272)]} 0 0
[junit4:junit4]   2> 2464228 T7459 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[47 (1437133756429238272)]} 0 4
[junit4:junit4]   2> 2464229 T7477 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[47]} 0 8
[junit4:junit4]   2> 2464232 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[48 (1437133756437626880)]} 0 0
[junit4:junit4]   2> 2464238 T7491 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[48 (1437133756441821184)]} 0 0
[junit4:junit4]   2> 2464239 T7458 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[48 (1437133756441821184)]} 0 3
[junit4:junit4]   2> 2464241 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[49 (1437133756447064064)]} 0 0
[junit4:junit4]   2> 2464248 T7492 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[49 (1437133756452306944)]} 0 0
[junit4:junit4]   2> 2464249 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[49 (1437133756452306944)]} 0 3
[junit4:junit4]   2> 2464253 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[50 (1437133756458598400)]} 0 1
[junit4:junit4]   2> 2464260 T7493 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[50 (1437133756463841280)]} 0 0
[junit4:junit4]   2> 2464261 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[50 (1437133756463841280)]} 0 5
[junit4:junit4]   2> 2464263 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[51 (1437133756470132736)]} 0 0
[junit4:junit4]   2> 2464272 T7488 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[51 (1437133756476424192)]} 0 0
[junit4:junit4]   2> 2464273 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[51 (1437133756476424192)]} 0 4
[junit4:junit4]   2> 2464274 T7475 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[51]} 0 8
[junit4:junit4]   2> 2464277 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[52 (1437133756484812800)]} 0 0
[junit4:junit4]   2> 2464286 T7489 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[52 (1437133756491104256)]} 0 1
[junit4:junit4]   2> 2464287 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[52 (1437133756491104256)]} 0 4
[junit4:junit4]   2> 2464288 T7472 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[52]} 0 8
[junit4:junit4]   2> 2464290 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[53 (1437133756498444288)]} 0 0
[junit4:junit4]   2> 2464297 T7505 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[53 (1437133756501590016)]} 0 0
[junit4:junit4]   2> 2464298 T7473 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[53 (1437133756501590016)]} 0 5
[junit4:junit4]   2> 2464302 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[54 (1437133756511027200)]} 0 0
[junit4:junit4]   2> 2464307 T7490 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[54 (1437133756514172928)]} 0 0
[junit4:junit4]   2> 2464309 T7459 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[54 (1437133756514172928)]} 0 4
[junit4:junit4]   2> 2464311 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[55 (1437133756520464384)]} 0 0
[junit4:junit4]   2> 2464317 T7491 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[55 (1437133756524658688)]} 0 0
[junit4:junit4]   2> 2464318 T7458 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[55 (1437133756524658688)]} 0 4
[junit4:junit4]   2> 2464323 T7439 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[56 (1437133756533047296)]} 0 0
[junit4:junit4]   2> 2464333 T7510 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[56 (1437133756540387328)]} 0 0
[junit4:junit4]   2> 2464334 T7474 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[56 (1437133756540387328)]} 0 6
[junit4:junit4]   2> 2464336 T7460 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[56]} 0 10
[junit4:junit4]   2> 2464339 T7441 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[57 (1437133756549824512)]} 0 0
[junit4:junit4]   2> 2464346 T7506 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[57 (1437133756554018816)]} 0 0
[junit4:junit4]   2> 2464348 T7476 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[57 (1437133756554018816)]} 0 5
[junit4:junit4]   2> 2464350 T7436 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[58 (1437133756561358848)]} 0 0
[junit4:junit4]   2> 2464357 T7507 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[58 (1437133756564504576)]} 0 0
[junit4:junit4]   2> 2464359 T7477 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[58 (1437133756564504576)]} 0 6
[junit4:junit4]   2> 2464363 T7437 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[59 (1437133756574990336)]} 0 0
[junit4:junit4]   2> 2464373 T7492 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[59 (1437133756582330368)]} 0 0
[junit4:junit4]   2> 2464374 T7456 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[59 (1437133756582330368)]} 0 4
[junit4:junit4]   2> 2464375 T7475 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[59]} 0 9
[junit4:junit4]   2> 2464377 T7438 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[60 (1437133756589670400)]} 0 0
[junit4:junit4]   2> 2464388 T7509 C1984 P50113 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[60 (1437133756598059008)]} 0 0
[junit4:junit4]   2> 2464389 T7472 C1981 P50094 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[60 (1437133756598059008)]} 0 4
[junit4:junit4]   2> 2464390 T7461 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[60]} 0 8
[junit4:junit4]   2> 2464394 T7440 C1985 P50073 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={wt=javabin&version=2} {add=[61 (1437133756606447616)]} 0 1
[junit4:junit4]   2> 2464401 T7493 C1983 P50103 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50085/_/u/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[61 (1437133756612739072)]} 0 0
[junit4:junit4]   2> 2464403 T7457 C1982 P50085 oasup.LogUpdateProcessor.finish [collection1] webapp=/_/u path=/update params={distrib.from=http://127.0.0.1:50094/_/u/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[61 (1437133756612739072)]} 0 4
[junit4:junit4]   

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

 2> 2496507 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2496507 T7514 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 2496509 T7423 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 50073
[junit4:junit4]   2> 2496528 T7423 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> 2496529 T7423 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50066 50066
[junit4:junit4]   1> client port:0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ChaosMonkeyShardSplitTest -Dtests.method=testDistribSearch -Dtests.seed=F4315BC07879041B -Dtests.slow=true -Dtests.locale=en_GB -Dtests.timezone=Asia/Nicosia -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   65.1s | ChaosMonkeyShardSplitTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Server at http://127.0.0.1:50085/_/u returned non ok status:500, message:Server Error
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F4315BC07879041B:75D7D5D80F266427]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:385)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4:junit4]    > 	at org.apache.solr.cloud.ShardSplitTest.splitShard(ShardSplitTest.java:228)
[junit4:junit4]    > 	at org.apache.solr.cloud.ChaosMonkeyShardSplitTest.doTest(ChaosMonkeyShardSplitTest.java:136)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:815)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 2496536 T7423 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {id=PostingsFormat(name=Lucene41WithOrds), _version_=PostingsFormat(name=MockSep), n_ti=Lucene41(blocksize=128)}, docValues:{}, sim=DefaultSimilarity, locale=en_GB, timezone=Asia/Nicosia
[junit4:junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_21 (64-bit)/cpus=2,threads=3,free=150291264,total=484442112
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestStressLucene, TestStressRecovery, TestQueryUtils, CopyFieldTest, TestUtils, SyncSliceTest, FullSolrCloudDistribCmdsTest, SystemInfoHandlerTest, UUIDFieldTest, TestShardHandlerFactory, PingRequestHandlerTest, SolrCmdDistributorTest, ShardSplitTest, SpellPossibilityIteratorTest, TestStressReorder, TestZkChroot, UnloadDistributedZkTest, QueryParsingTest, CurrencyFieldXmlFileTest, TestQuerySenderListener, UniqFieldsUpdateProcessorFactoryTest, TestFastOutputStream, ShardRoutingTest, TermVectorComponentDistributedTest, XsltUpdateRequestHandlerTest, LeaderElectionIntegrationTest, SuggesterWFSTTest, ReturnFieldsTest, TestCloudManagedSchemaAddField, SliceStateUpdateTest, TestQuerySenderNoQuery, SliceStateTest, TestSolrXml, SoftAutoCommitTest, TestSolrDeletionPolicy1, DistanceFunctionTest, TestQueryTypes, ShardRoutingCustomTest, TestRemoteStreaming, TestMaxScoreQueryParser, TestRealTimeGet, LeaderElectionTest, BasicDistributedZkTest, TestBM25SimilarityFactory, TestBinaryResponseWriter, FileUtilsTest, TestFaceting, SynonymTokenizerTest, TestAnalyzedSuggestions, TestDFRSimilarityFactory, TestJmxIntegration, OverseerCollectionProcessorTest, StandardRequestHandlerTest, MinimalSchemaTest, SampleTest, DisMaxRequestHandlerTest, DOMUtilTest, TestDynamicFieldCollectionResource, TestCopyFieldCollectionResource, TestReplicationHandler, TestSchemaResource, TestConfig, TestFiltering, OpenExchangeRatesOrgProviderTest, TestGroupingSearch, SimplePostToolTest, TestCSVLoader, TestSchemaSimilarityResource, TestPropInjectDefaults, TestFieldTypeCollectionResource, DistributedSpellCheckComponentTest, TestLRUCache, TestSolrJ, TestSchemaNameResource, SolrIndexSplitterTest, TestRangeQuery, BadCopyFieldTest, TestManagedSchema, PeerSyncTest, TestSort, CachingDirectoryFactoryTest, SOLR749Test, MultiTermTest, DebugComponentTest, TestPhraseSuggestions, ZkSolrClientTest, PreAnalyzedUpdateProcessorTest, TestLFUCache, PolyFieldTest, QueryEqualityTest, DocValuesTest, StatsComponentTest, TestSolrQueryParserResource, TestSerializedLuceneMatchVersion, IndexSchemaRuntimeFieldTest, DocValuesMultiTest, TestRecovery, TestRTGBase, TestFastWriter, TestUniqueKeyFieldResource, TestRandomDVFaceting, SuggesterTest, TestCloudManagedSchema, TestPropInject, FastVectorHighlighterTest, TestFieldCollectionResource, TestLazyCores, TestFastLRUCache, TestValueSourceCache, SolrRequestParserTest, ChaosMonkeySafeLeaderTest, TestSchemaVersionResource, TestPerFieldSimilarity, JsonLoaderTest, TestNumberUtils, TestSystemIdResolver, SolrPluginUtilsTest, OverseerTest, TestExtendedDismaxParser, TestJmxMonitoredMap, SolrInfoMBeanTest, ChaosMonkeyNothingIsSafeTest, TermVectorComponentTest, IndexSchemaTest, TestCharFilters, DirectSolrSpellCheckerTest, IndexBasedSpellCheckerTest, TestLuceneMatchVersion, SolrCoreCheckLockOnStartupTest, TestWordDelimiterFilterFactory, SpellCheckComponentTest, BasicFunctionalityTest, ClusterStateUpdateTest, URLClassifyProcessorTest, DistributedQueryElevationComponentTest, CollectionsAPIDistributedZkTest, DistributedTermsComponentTest, TestAddFieldRealTimeGet, TestDocSet, BasicDistributedZk2Test, OpenCloseCoreStressTest, BasicZkTest, RecoveryZkTest, TestRandomFaceting, ZkCLITest, TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestHashPartitioner, ZkControllerTest, TestJoin, TestMultiCoreConfBootstrap, TestReload, TestStressVersions, HardAutoCommitTest, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, QueryElevationComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFunctionQuery, DirectUpdateHandlerTest, TestBadConfig, TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, TestFoldingMultitermQuery, SuggesterTSTTest, TestTrie, SpatialFilterTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, CacheHeaderTest, TestSurroundQueryParser, LukeRequestHandlerTest, TestWriterPerf, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, LoggingHandlerTest, RegexBoostProcessorTest, TestCollationField, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestCSVResponseWriter, MBeansHandlerTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, CoreContainerCoreInitFailuresTest, HighlighterConfigTest, IndexReaderFactoryTest, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, TestBinaryField, TestSearchPerf, NumericFieldsTest, TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestCodecSupport, TestXIncludeConfig, EchoParamsTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest, TestPluginEnable, TimeZoneUtilsTest, ResourceLoaderTest, ScriptEngineTest, PluginInfoTest, DateMathParserTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, PrimUtilsTest, TestSuggestSpellingConverter, DateFieldTest, SpellingQueryConverterTest, RAMDirectoryFactoryTest, ClusterStateTest, TestDocumentBuilder, ZkNodePropsTest, CircularListTest, SolrTestCaseJ4Test, AliasIntegrationTest, ChaosMonkeyShardSplitTest]
[junit4:junit4] Completed in 65.13s, 1 test, 1 error <<< FAILURES!

[...truncated 36 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:380: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:360: 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\solr\build.xml:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:437: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1240: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:884: There were test failures: 296 suites, 1243 tests, 1 error, 18 ignored (12 assumptions)

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



Mime
View raw message