lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_35) - Build # 922 - Failure!
Date Tue, 25 Sep 2012 14:37:39 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/922/
Java: 32bit/jdk1.6.0_35 -server -XX:+UseSerialGC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZk2Test

Error Message:
ERROR: SolrIndexSearcher opens=73 closes=72

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=73 closes=72
	at __randomizedtesting.SeedInfo.seed([48BE871FDAA89ABB]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:241)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:96)
	at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
	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:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:662)


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZkTest

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([48BE871FDAA89ABB]:0)
	at org.apache.solr.SolrTestCaseJ4.useFactory(SolrTestCaseJ4.java:106)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.beforeThisClass(AbstractDistribZkTestBase.java:47)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:677)
	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:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:662)




Build Log:
[...truncated 7218 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   2> 5 T1553 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicDistributedZk2Test-1348583215897
[junit4:junit4]   2> 10 T1553 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 17 T1554 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 18 T1554 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 20 T1554 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 120 T1553 oasc.ZkTestServer.run start zk server on port:51347
[junit4:junit4]   2> 120 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1bf35b7
[junit4:junit4]   2> 121 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 122 T1559 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 123 T1559 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 124 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51350
[junit4:junit4]   2> 124 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51350
[junit4:junit4]   2> 124 T1557 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 135 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280000 with negotiated timeout 10000 for client /127.0.0.1:51350
[junit4:junit4]   2> 135 T1559 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280000, negotiated timeout = 10000
[junit4:junit4]   2> 135 T1560 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1bf35b7 name:ZooKeeperConnection Watcher:127.0.0.1:51347 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 135 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 135 T1553 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 141 T1558 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139fdd2d3280000
[junit4:junit4]   2> 142 T1553 oaz.ZooKeeper.close Session: 0x139fdd2d3280000 closed
[junit4:junit4]   2> 142 T1560 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 142 T1555 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139fdd2d3280000, likely client has closed socket
[junit4:junit4]   2> 142 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@16ea32e
[junit4:junit4]   2> 149 T1555 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51350 which had sessionid 0x139fdd2d3280000
[junit4:junit4]   2> 149 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 149 T1561 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 152 T1561 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 152 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51353
[junit4:junit4]   2> 152 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51353
[junit4:junit4]   2> 154 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280001 with negotiated timeout 10000 for client /127.0.0.1:51353
[junit4:junit4]   2> 154 T1561 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280001, negotiated timeout = 10000
[junit4:junit4]   2> 154 T1562 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16ea32e name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 154 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 155 T1553 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 161 T1553 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 165 T1553 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 168 T1553 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 175 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 175 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 184 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 185 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 189 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 191 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 196 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 196 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 200 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 201 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 205 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 205 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 211 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 211 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 216 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 217 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 221 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 221 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 225 T1553 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 226 T1553 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 231 T1558 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139fdd2d3280001
[junit4:junit4]   2> 233 T1555 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51353 which had sessionid 0x139fdd2d3280001
[junit4:junit4]   2> 233 T1553 oaz.ZooKeeper.close Session: 0x139fdd2d3280001 closed
[junit4:junit4]   2> 233 T1562 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 236 T1553 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 240 T1553 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:51354
[junit4:junit4]   2> 240 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 240 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 241 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 291 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 292 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 292 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 293 T1553 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 293 T1553 oasc.CoreContainer.<init> New CoreContainer 27663898
[junit4:junit4]   2> 294 T1553 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 294 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 324 T1553 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 352 T1553 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51347/solr
[junit4:junit4]   2> 352 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@e4d346
[junit4:junit4]   2> 354 T1572 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 354 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 356 T1572 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 356 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51357
[junit4:junit4]   2> 356 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51357
[junit4:junit4]   2> 359 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280002 with negotiated timeout 5000 for client /127.0.0.1:51357
[junit4:junit4]   2> 359 T1572 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280002, negotiated timeout = 5000
[junit4:junit4]   2> 359 T1573 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e4d346 name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 359 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 362 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 364 T1553 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 367 T1553 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51354_solr
[junit4:junit4]   2> 368 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51354_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51354_solr
[junit4:junit4]   2> 370 T1553 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51354_solr
[junit4:junit4]   2> 374 T1553 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 375 T1553 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 375 T1553 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 375 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 376 T1553 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 376 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 376 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 377 T1553 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 377 T1553 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 377 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 387 T1553 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 396 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 398 T1553 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 401 T1553 oasc.Overseer.start Overseer (id=88380749638008834-127.0.0.1:51354_solr-n_0000000000) starting
[junit4:junit4]   2> 402 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 404 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 405 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 407 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 411 T1575 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 411 T1553 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 415 T1553 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 418 T1574 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 423 T1553 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 423 T1553 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 424 T1553 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 424 T1553 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 426 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 430 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 431 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 482 T1553 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 563 T1553 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 566 T1553 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 572 T1553 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1296 T1553 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1308 T1553 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1313 T1553 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1333 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1338 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1343 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1345 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1347 T1553 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/control/data\
[junit4:junit4]   2> 1348 T1553 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@159e6e8
[junit4:junit4]   2> 1349 T1553 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/control/data\index/
[junit4:junit4]   2> 1351 T1553 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1351 T1553 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\control\data\index forceNew:false
[junit4:junit4]   2> 1364 T1553 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1671ec; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1364 T1553 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1367 T1553 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1369 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1369 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1370 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1370 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1371 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1371 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1371 T1553 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1372 T1553 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1372 T1553 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1372 T1553 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1373 T1553 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1374 T1553 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1374 T1553 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1374 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1375 T1553 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1376 T1553 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1376 T1553 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1376 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1377 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1377 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1377 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1377 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1379 T1553 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1379 T1553 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1380 T1553 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1383 T1553 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1390 T1553 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1391 T1553 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1396 T1553 oass.SolrIndexSearcher.<init> Opening Searcher@3a454 main
[junit4:junit4]   2> 1399 T1553 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1399 T1553 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1399 T1553 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1412 T1553 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1474 T1576 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3a454 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1478 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1483 T1553 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1483 T1553 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51354/solr shardId:control_shard
[junit4:junit4]   2> 1485 T1553 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1498 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:delete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1499 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1500 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1503 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1505 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1521 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2042 T1553 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2043 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2043 T1553 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51354/solr/collection1/
[junit4:junit4]   2> 2045 T1553 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2045 T1553 oasc.SyncStrategy.syncToMe http://127.0.0.1:51354/solr/collection1/ has no replicas
[junit4:junit4]   2> 2045 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51354/solr/collection1/
[junit4:junit4]   2> 2045 T1553 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 2054 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2069 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2079 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2114 T1553 oasc.ZkController.register We are http://127.0.0.1:51354/solr/collection1/ and leader is http://127.0.0.1:51354/solr/collection1/
[junit4:junit4]   2> 2114 T1553 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51354/solr
[junit4:junit4]   2> 2114 T1553 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2119 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2120 T1553 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2120 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2121 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2569 T1553 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2572 T1553 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:51358
[junit4:junit4]   2> 2572 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2573 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020
[junit4:junit4]   2> 2573 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020\'
[junit4:junit4]   2> 2615 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2615 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2616 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020
[junit4:junit4]   2> 2616 T1553 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020\solr.xml
[junit4:junit4]   2> 2616 T1553 oasc.CoreContainer.<init> New CoreContainer 13361585
[junit4:junit4]   2> 2617 T1553 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020\'
[junit4:junit4]   2> 2617 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020\'
[junit4:junit4]   2> 2625 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2653 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2659 T1553 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2678 T1553 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51347/solr
[junit4:junit4]   2> 2678 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@cfaee1
[junit4:junit4]   2> 2681 T1586 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 2681 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2684 T1586 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 2684 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51361
[junit4:junit4]   2> 2685 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51361
[junit4:junit4]   2> 2687 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280003 with negotiated timeout 5000 for client /127.0.0.1:51361
[junit4:junit4]   2> 2687 T1586 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280003, negotiated timeout = 5000
[junit4:junit4]   2> 2687 T1587 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@cfaee1 name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2688 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2689 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2691 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2693 T1553 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51358_solr
[junit4:junit4]   2> 2693 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51358_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51358_solr
[junit4:junit4]   2> 2695 T1553 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51358_solr
[junit4:junit4]   2> 2699 T1573 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2700 T1553 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2700 T1553 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2701 T1553 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2701 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2703 T1553 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2703 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2704 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2705 T1553 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2705 T1553 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2706 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2723 T1553 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2731 T1553 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020\collection1
[junit4:junit4]   2> 2731 T1553 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2733 T1553 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2733 T1553 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2736 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020\collection1\'
[junit4:junit4]   2> 2737 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty1-1348583218020/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2738 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty1-1348583218020/collection1/lib/README' to classloader
[junit4:junit4]   2> 2792 T1553 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 2884 T1553 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2886 T1553 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2895 T1553 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3686 T1553 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3697 T1553 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3703 T1553 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3726 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3733 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3741 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3743 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3745 T1553 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1348583218020\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty1\
[junit4:junit4]   2> 3745 T1553 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@159e6e8
[junit4:junit4]   2> 3747 T1553 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty1\index/
[junit4:junit4]   2> 3748 T1553 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3749 T1553 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty1\index forceNew:false
[junit4:junit4]   2> 3762 T1553 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c0d2ed; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3762 T1553 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3764 T1553 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3766 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3766 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3766 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3768 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3768 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3769 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3769 T1553 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3770 T1553 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3770 T1553 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3771 T1553 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3771 T1553 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3772 T1553 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3772 T1553 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3773 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3773 T1553 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3774 T1553 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3774 T1553 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3774 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3775 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3775 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3776 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3776 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3777 T1553 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3778 T1553 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3780 T1553 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3783 T1553 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3788 T1553 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3791 T1553 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3796 T1553 oass.SolrIndexSearcher.<init> Opening Searcher@bbcd00 main
[junit4:junit4]   2> 3798 T1553 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3798 T1553 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3798 T1553 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3810 T1553 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3875 T1588 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bbcd00 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4281 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4293 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4293 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4421 T1553 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4422 T1553 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51358/solr shardId:shard1
[junit4:junit4]   2> 4423 T1553 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 4438 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280003 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 4439 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4440 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280003 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4444 T1553 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4444 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4444 T1553 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51358/solr/collection1/
[junit4:junit4]   2> 4445 T1553 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4445 T1553 oasc.SyncStrategy.syncToMe http://127.0.0.1:51358/solr/collection1/ has no replicas
[junit4:junit4]   2> 4445 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51358/solr/collection1/
[junit4:junit4]   2> 4446 T1553 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 4453 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4841 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4859 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4859 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4894 T1553 oasc.ZkController.register We are http://127.0.0.1:51358/solr/collection1/ and leader is http://127.0.0.1:51358/solr/collection1/
[junit4:junit4]   2> 4894 T1553 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51358/solr
[junit4:junit4]   2> 4894 T1553 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4899 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4900 T1553 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 4900 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4900 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5278 T1553 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5281 T1553 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:51362
[junit4:junit4]   2> 5281 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5282 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799
[junit4:junit4]   2> 5282 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799\'
[junit4:junit4]   2> 5326 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5326 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5326 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799
[junit4:junit4]   2> 5327 T1553 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799\solr.xml
[junit4:junit4]   2> 5327 T1553 oasc.CoreContainer.<init> New CoreContainer 28503597
[junit4:junit4]   2> 5327 T1553 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799\'
[junit4:junit4]   2> 5328 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799\'
[junit4:junit4]   2> 5352 T1553 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5370 T1553 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51347/solr
[junit4:junit4]   2> 5371 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@19dc05b
[junit4:junit4]   2> 5372 T1598 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 5372 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5377 T1598 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 5377 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51365
[junit4:junit4]   2> 5377 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51365
[junit4:junit4]   2> 5387 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280004 with negotiated timeout 5000 for client /127.0.0.1:51365
[junit4:junit4]   2> 5387 T1598 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280004, negotiated timeout = 5000
[junit4:junit4]   2> 5387 T1599 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19dc05b name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5387 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5389 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5391 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5393 T1553 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51362_solr
[junit4:junit4]   2> 5395 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51362_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51362_solr
[junit4:junit4]   2> 5397 T1553 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51362_solr
[junit4:junit4]   2> 5400 T1573 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5400 T1587 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5402 T1553 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5403 T1553 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5403 T1553 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5403 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5404 T1553 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5404 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5404 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5405 T1553 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5405 T1553 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5405 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5408 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5421 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5421 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5428 T1553 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5439 T1553 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799\collection1
[junit4:junit4]   2> 5439 T1553 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5441 T1553 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5441 T1553 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5444 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799\collection1\'
[junit4:junit4]   2> 5445 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty2-1348583220799/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5446 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty2-1348583220799/collection1/lib/README' to classloader
[junit4:junit4]   2> 5501 T1553 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 5585 T1553 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5587 T1553 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5593 T1553 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6329 T1553 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6341 T1553 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6346 T1553 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6365 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6370 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6376 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6378 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6380 T1553 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1348583220799\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty2\
[junit4:junit4]   2> 6380 T1553 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@159e6e8
[junit4:junit4]   2> 6382 T1553 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty2\index/
[junit4:junit4]   2> 6383 T1553 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6383 T1553 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty2\index forceNew:false
[junit4:junit4]   2> 6398 T1553 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a4dfd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6398 T1553 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6401 T1553 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6402 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6402 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6403 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6404 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6405 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6405 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6405 T1553 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6406 T1553 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6406 T1553 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6407 T1553 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6407 T1553 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6408 T1553 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6408 T1553 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6408 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6408 T1553 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6409 T1553 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6410 T1553 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6410 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6412 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6412 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6413 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6413 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6413 T1553 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6415 T1553 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6415 T1553 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6419 T1553 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6427 T1553 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6429 T1553 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 6437 T1553 oass.SolrIndexSearcher.<init> Opening Searcher@17e77a9 main
[junit4:junit4]   2> 6440 T1553 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6440 T1553 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6441 T1553 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6454 T1553 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6516 T1600 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17e77a9 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7054 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7064 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7064 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7066 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7601 T1553 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7601 T1553 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51362/solr shardId:shard2
[junit4:junit4]   2> 7603 T1553 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 7618 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280004 type:delete cxid:0x41 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 7620 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7621 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280004 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7626 T1553 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7628 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7628 T1553 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51362/solr/collection1/
[junit4:junit4]   2> 7628 T1553 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7628 T1553 oasc.SyncStrategy.syncToMe http://127.0.0.1:51362/solr/collection1/ has no replicas
[junit4:junit4]   2> 7629 T1553 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51362/solr/collection1/
[junit4:junit4]   2> 7630 T1553 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 7646 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280004 type:create cxid:0x4b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8156 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8172 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8173 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8173 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8198 T1553 oasc.ZkController.register We are http://127.0.0.1:51362/solr/collection1/ and leader is http://127.0.0.1:51362/solr/collection1/
[junit4:junit4]   2> 8198 T1553 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51362/solr
[junit4:junit4]   2> 8198 T1553 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8201 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8203 T1553 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 8204 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8204 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8647 T1553 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 8649 T1553 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:51366
[junit4:junit4]   2> 8649 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8650 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104
[junit4:junit4]   2> 8650 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104\'
[junit4:junit4]   2> 8685 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8685 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8686 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104
[junit4:junit4]   2> 8686 T1553 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104\solr.xml
[junit4:junit4]   2> 8687 T1553 oasc.CoreContainer.<init> New CoreContainer 15951948
[junit4:junit4]   2> 8687 T1553 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104\'
[junit4:junit4]   2> 8688 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104\'
[junit4:junit4]   2> 8714 T1553 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8733 T1553 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51347/solr
[junit4:junit4]   2> 8733 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1603b48
[junit4:junit4]   2> 8737 T1610 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 8737 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8739 T1610 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 8739 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51369
[junit4:junit4]   2> 8739 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51369
[junit4:junit4]   2> 8755 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280005 with negotiated timeout 5000 for client /127.0.0.1:51369
[junit4:junit4]   2> 8755 T1610 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280005, negotiated timeout = 5000
[junit4:junit4]   2> 8755 T1611 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1603b48 name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8757 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8758 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8763 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8767 T1553 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51366_solr
[junit4:junit4]   2> 8768 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51366_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51366_solr
[junit4:junit4]   2> 8770 T1553 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51366_solr
[junit4:junit4]   2> 8770 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8776 T1573 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8776 T1599 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8776 T1587 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8780 T1553 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8780 T1553 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8780 T1553 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8781 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8781 T1553 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8782 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8782 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8782 T1553 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8783 T1553 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8783 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8788 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8789 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8796 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8803 T1553 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8812 T1553 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104\collection1
[junit4:junit4]   2> 8812 T1553 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8813 T1553 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8814 T1553 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8817 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104\collection1\'
[junit4:junit4]   2> 8819 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty3-1348583224104/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8820 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty3-1348583224104/collection1/lib/README' to classloader
[junit4:junit4]   2> 8880 T1553 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 9149 T1553 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9151 T1553 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9158 T1553 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9973 T1553 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9984 T1553 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9991 T1553 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10013 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10020 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10025 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10027 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10029 T1553 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1348583224104\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty3\
[junit4:junit4]   2> 10030 T1553 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@159e6e8
[junit4:junit4]   2> 10032 T1553 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty3\index/
[junit4:junit4]   2> 10033 T1553 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 10034 T1553 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index forceNew:false
[junit4:junit4]   2> 10047 T1553 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2db5c0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10047 T1553 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10050 T1553 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 10052 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 10052 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 10052 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 10054 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 10054 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 10054 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 10056 T1553 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 10056 T1553 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10057 T1553 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10057 T1553 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 10058 T1553 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 10058 T1553 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10058 T1553 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 10059 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 10059 T1553 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 10061 T1553 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10061 T1553 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10062 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10062 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10063 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10063 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10064 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10064 T1553 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10065 T1553 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 10065 T1553 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 10071 T1553 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10077 T1553 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 10079 T1553 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 10084 T1553 oass.SolrIndexSearcher.<init> Opening Searcher@19884db main
[junit4:junit4]   2> 10087 T1553 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10087 T1553 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10087 T1553 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 10101 T1553 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 10176 T1612 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19884db main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10584 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10593 T1611 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10594 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10594 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10594 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10777 T1553 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 10777 T1553 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51366/solr shardId:shard1
[junit4:junit4]   2> 10784 T1553 oasc.ZkController.register We are http://127.0.0.1:51366/solr/collection1/ and leader is http://127.0.0.1:51358/solr/collection1/
[junit4:junit4]   2> 10784 T1553 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51366/solr
[junit4:junit4]   2> 10786 T1553 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 10786 T1553 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C128 name=collection1 org.apache.solr.core.SolrCore@1d6bb79 url=http://127.0.0.1:51366/solr/collection1 node=127.0.0.1:51366_solr
[junit4:junit4]   2> 10787 T1613 C128 P51366 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 10788 T1613 C128 P51366 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 10788 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10798 T1613 C128 P51366 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:51358/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 10799 T1613 C128 P51366 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:51366/solr START replicas=[http://127.0.0.1:51358/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 10799 T1553 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 10799 T1613 C128 P51366 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 10799 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 10801 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10809 T1613 C128 P51366 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 10810 T1613 C128 P51366 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 10810 T1613 C128 P51366 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C129 name=collection1 org.apache.solr.core.SolrCore@4e6b26 url=http://127.0.0.1:51358/solr/collection1 node=127.0.0.1:51358_solr
[junit4:junit4]   2> 10812 T1579 C129 P51358 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 11197 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11221 T1611 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11221 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11222 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11222 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11244 T1553 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 11248 T1553 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:51372
[junit4:junit4]   2> 11249 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 11250 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704
[junit4:junit4]   2> 11250 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704\'
[junit4:junit4]   2> 11289 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 11289 T1553 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 11290 T1553 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704
[junit4:junit4]   2> 11290 T1553 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704\solr.xml
[junit4:junit4]   2> 11290 T1553 oasc.CoreContainer.<init> New CoreContainer 33176587
[junit4:junit4]   2> 11292 T1553 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704\'
[junit4:junit4]   2> 11292 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704\'
[junit4:junit4]   2> 11317 T1553 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 11338 T1553 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51347/solr
[junit4:junit4]   2> 11338 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1507806
[junit4:junit4]   2> 11341 T1624 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 11341 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 11344 T1624 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 11344 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51375
[junit4:junit4]   2> 11345 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51375
[junit4:junit4]   2> 11347 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280006 with negotiated timeout 5000 for client /127.0.0.1:51375
[junit4:junit4]   2> 11347 T1624 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280006, negotiated timeout = 5000
[junit4:junit4]   2> 11348 T1625 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1507806 name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 11348 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 11350 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11352 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11354 T1553 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51372_solr
[junit4:junit4]   2> 11354 T1558 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139fdd2d3280006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51372_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51372_solr
[junit4:junit4]   2> 11356 T1553 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51372_solr
[junit4:junit4]   2> 11361 T1611 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 11361 T1573 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 11361 T1587 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 11361 T1599 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 11363 T1553 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 11363 T1553 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 11363 T1553 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 11365 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 11365 T1553 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 11365 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 11366 T1553 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 11366 T1553 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 11366 T1553 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 11367 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 11381 T1553 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 11388 T1553 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704\collection1
[junit4:junit4]   2> 11388 T1553 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 11390 T1553 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 11390 T1553 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 11392 T1553 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704\collection1\'
[junit4:junit4]   2> 11394 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty4-1348583226704/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 11395 T1553 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty4-1348583226704/collection1/lib/README' to classloader
[junit4:junit4]   2> 11456 T1553 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 11551 T1553 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 11553 T1553 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 11560 T1553 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 12413 T1553 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 12425 T1553 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 12431 T1553 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 12466 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 12472 T1553 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 12478 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 12481 T1553 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 12483 T1553 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1348583226704\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty4\
[junit4:junit4]   2> 12483 T1553 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@159e6e8
[junit4:junit4]   2> 12484 T1553 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty4\index/
[junit4:junit4]   2> 12486 T1553 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty4\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 12487 T1553 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty4\index forceNew:false
[junit4:junit4]   2> 12504 T1553 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1067cdc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12504 T1553 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 12506 T1553 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 12509 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 12509 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 12510 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 12510 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 12511 T1553 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 12511 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 12511 T1553 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 12512 T1553 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 12512 T1553 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 12513 T1553 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 12515 T1553 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 12515 T1553 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 12515 T1553 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 12516 T1553 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 12516 T1553 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 12517 T1553 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 12517 T1553 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12518 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12518 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12518 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12519 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12519 T1553 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12520 T1553 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12522 T1553 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 12522 T1553 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 12526 T1553 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 12534 T1553 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 12537 T1553 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 12542 T1553 oass.SolrIndexSearcher.<init> Opening Searcher@60fe49 main
[junit4:junit4]   2> 12545 T1553 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 12545 T1553 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 12546 T1553 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 12559 T1553 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 12630 T1626 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@60fe49 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 13013 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13023 T1611 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13024 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13024 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13024 T1625 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13024 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13235 T1553 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 13235 T1553 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51372/solr shardId:shard2
[junit4:junit4]   2> 13246 T1553 oasc.ZkController.register We are http://127.0.0.1:51372/solr/collection1/ and leader is http://127.0.0.1:51362/solr/collection1/
[junit4:junit4]   2> 13246 T1553 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51372/solr
[junit4:junit4]   2> 13246 T1553 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 13248 T1553 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C130 name=collection1 org.apache.solr.core.SolrCore@8178ac url=http://127.0.0.1:51372/solr/collection1 node=127.0.0.1:51372_solr
[junit4:junit4]   2> 13249 T1627 C130 P51372 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 13250 T1627 C130 P51372 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 13250 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13253 T1627 C130 P51372 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:51362/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 13253 T1627 C130 P51372 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:51372/solr START replicas=[http://127.0.0.1:51362/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 13253 T1553 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 13255 T1627 C130 P51372 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 13255 T1553 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 13256 T1627 C130 P51372 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 13256 T1627 C130 P51372 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 13256 T1627 C130 P51372 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 13255 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C131 name=collection1 org.apache.solr.core.SolrCore@94d29c url=http://127.0.0.1:51362/solr/collection1 node=127.0.0.1:51362_solr
[junit4:junit4]   2> 13271 T1592 C131 P51362 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 13274 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1e178fb
[junit4:junit4]   2> 13275 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 13275 T1629 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 13279 T1629 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 13279 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51380
[junit4:junit4]   2> 13279 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51380
[junit4:junit4]   2> 13281 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280007 with negotiated timeout 10000 for client /127.0.0.1:51380
[junit4:junit4]   2> 13281 T1629 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280007, negotiated timeout = 10000
[junit4:junit4]   2> 13281 T1630 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e178fb name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 13282 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 13284 T1553 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13286 T1553 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 13286 T1553 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 13292 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C132 name=collection1 org.apache.solr.core.SolrCore@923428 url=http://127.0.0.1:51354/solr/collection1 node=127.0.0.1:51354_solr
[junit4:junit4]   2> 13308 T1565 C132 P51354 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1671ec; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13308 T1565 C132 P51354 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 13314 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[1 (1414092008132182016)]} 0 13
[junit4:junit4]   2> 13315 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ed1e69
[junit4:junit4]   2> 13316 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 13316 T1631 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 13318 T1631 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 13318 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51384
[junit4:junit4]   2> 13319 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51384
[junit4:junit4]   2> 13322 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280008 with negotiated timeout 10000 for client /127.0.0.1:51384
[junit4:junit4]   2> 13322 T1631 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280008, negotiated timeout = 10000
[junit4:junit4]   2> 13322 T1632 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ed1e69 name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 13323 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 13324 T1553 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13346 T1581 C129 P51358 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c0d2ed; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13347 T1581 C129 P51358 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2>  C128_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:51366_solr, base_url=http://127.0.0.1:51366/solr}
[junit4:junit4]   2> 13374 T1603 C128 P51366 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2db5c0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13375 T1603 C128 P51366 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 13380 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=13 
[junit4:junit4]   2> 13381 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=45 
[junit4:junit4]   2> 13381 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[1]} 0 52
[junit4:junit4]   2> 13382 T1553 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):600
[junit4:junit4]   2> 13383 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13626 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13636 T1611 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13636 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13637 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13637 T1625 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13637 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13637 T1630 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13639 T1632 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14377 T1613 C128 P51366 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 14377 T1613 C128 P51366 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\tlog\tlog.0000000000000000000 refcount=1}}
[junit4:junit4]   2> 14377 T1613 C128 P51366 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:51358/solr/collection1/. core=collection1
[junit4:junit4]   2> 14377 T1613 C128 P51366 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 14388 T1582 C129 P51358 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14479 T1582 C129 P51358 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c0d2ed; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c0d2ed; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0_Block_0.pos, _0_Block_0.tip, _0_Block_0.tim, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0.fnm, _0_Block_0.doc, _0_nrm.cfs, _0_Direct_0.frq, _0_MockRandom_0.skp, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tip, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Lucene40WithOrds_0.prx, _0_Direct_0.prx, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0_MockRandom_0.tim, _0.fdt]
[junit4:junit4]   2> 14480 T1582 C129 P51358 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 14495 T1582 C129 P51358 oass.SolrIndexSearcher.<init> Opening Searcher@142431b main
[junit4:junit4]   2> 14500 T1582 C129 P51358 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14500 T1588 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@142431b main{StandardDirectoryReader(segments_2:3 _0(4.1):C1)}
[junit4:junit4]   2> 14501 T1582 C129 P51358 /update {waitSearcher=true&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 113
[junit4:junit4]   2> 14502 T1613 C128 P51366 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 14502 T1613 C128 P51366 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 14504 T1584 C129 P51358 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 14504 T1584 C129 P51358 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 14505 T1613 C128 P51366 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 14505 T1613 C128 P51366 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 14505 T1613 C128 P51366 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 14511 T1584 C129 P51358 REQ /replication {command=filelist&generation=2&wt=javabin&qt=/replication&version=2} status=0 QTime=3 
[junit4:junit4]   2> 14512 T1613 C128 P51366 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 27
[junit4:junit4]   2> 14530 T1613 C128 P51366 oash.SnapPuller.fetchLatestIndex Starting download to .\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index.20120925102710402 fullCopy=true
[junit4:junit4]   2> 14535 T1584 C129 P51358 REQ /replication {file=_0_Block_0.tip&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14542 T1584 C129 P51358 REQ /replication {file=_0_Block_0.pos&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14549 T1584 C129 P51358 REQ /replication {file=_0_Block_0.tim&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14556 T1584 C129 P51358 REQ /replication {file=_0_Lucene40WithOrds_0.tii&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14562 T1584 C129 P51358 REQ /replication {file=_0_Lucene40WithOrds_0.frq&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14568 T1584 C129 P51358 REQ /replication {file=_0.si&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14583 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14586 T1584 C129 P51358 REQ /replication {file=_0_Lucene40WithOrds_0.tib&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14592 T1584 C129 P51358 REQ /replication {file=_0_Block_0.doc&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14598 T1584 C129 P51358 REQ /replication {file=_0.fnm&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14605 T1584 C129 P51358 REQ /replication {file=_0_nrm.cfs&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14611 T1584 C129 P51358 REQ /replication {file=_0_Direct_0.frq&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14618 T1584 C129 P51358 REQ /replication {file=_0_MockRandom_0.skp&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14619 T1613 C128 P51366 oash.SnapPuller$FileFetcher.fetchPackets WARNING No content recieved for file: {name=_0_MockRandom_0.skp, lastmodified=1348583230315, size=0}
[junit4:junit4]   2> 14625 T1584 C129 P51358 REQ /replication {file=_0_MockRandom_0.tip&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14630 T1584 C129 P51358 REQ /replication {file=_0_MockRandom_0.sd&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14636 T1584 C129 P51358 REQ /replication {file=_0_MockRandom_0.frq&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14642 T1584 C129 P51358 REQ /replication {file=_0_MockRandom_0.doc&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14649 T1584 C129 P51358 REQ /replication {file=_0_nrm.cfe&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14655 T1584 C129 P51358 REQ /replication {file=_0_MockRandom_0.pos&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14662 T1584 C129 P51358 REQ /replication {file=segments_2&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14667 T1584 C129 P51358 REQ /replication {file=_0_Direct_0.tip&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14674 T1584 C129 P51358 REQ /replication {file=_0.fdx&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14680 T1584 C129 P51358 REQ /replication {file=_0_Direct_0.prx&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14687 T1584 C129 P51358 REQ /replication {file=_0_Lucene40WithOrds_0.prx&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14702 T1584 C129 P51358 REQ /replication {file=_0_MockRandom_0.pyl&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14703 T1613 C128 P51366 oash.SnapPuller$FileFetcher.fetchPackets WARNING No content recieved for file: {name=_0_MockRandom_0.pyl, lastmodified=1348583230330, size=0}
[junit4:junit4]   2> 14708 T1584 C129 P51358 REQ /replication {file=_0_MockRandom_0.tim&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14714 T1584 C129 P51358 REQ /replication {file=_0_Direct_0.tim&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14720 T1584 C129 P51358 REQ /replication {file=_0.fdt&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 14721 T1613 C128 P51366 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 14723 T1613 C128 P51366 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20120925102710402
[junit4:junit4]   2> 14727 T1613 C128 P51366 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 14727 T1613 C128 P51366 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 14727 T1613 C128 P51366 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 14736 T1613 C128 P51366 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty3\index/ new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895/jetty3\index.20120925102710402
[junit4:junit4]   2> 14738 T1613 C128 P51366 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index.20120925102710402 forceNew:true
[junit4:junit4]   2> 14749 T1613 C128 P51366 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index.20120925102710402 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7926de; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0_Block_0.tip, _0_Block_0.pos, _0_Block_0.tim, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0_Block_0.doc, _0.fnm, _0_nrm.cfs, _0_Direct_0.frq, _0_MockRandom_0.skp, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tip, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Lucene40WithOrds_0.prx, _0_Direct_0.prx, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0_MockRandom_0.tim, _0.fdt]
[junit4:junit4]   2> 14749 T1613 C128 P51366 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 14750 T1613 C128 P51366 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 14754 T1613 C128 P51366 oass.SolrIndexSearcher.<init> Opening Searcher@789af main
[junit4:junit4]   2> 14755 T1612 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@789af main{StandardDirectoryReader(segments_2:3:nrt _0(4.1):C1)}
[junit4:junit4]   2> 14755 T1612 oasc.CachingDirectoryFactory.close Closing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index
[junit4:junit4]   2> 14757 T1612 oash.SnapPuller$2.onClose removing old index directory .\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\index
[junit4:junit4]   2> 14761 T1613 C128 P51366 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14762 T1613 C128 P51366 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14771 T1613 C128 P51366 oasc.RecoveryStrategy.replay Replaying buffered documents. core=collection1
[junit4:junit4]   2> 14771 T1636 C128 P51366 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=.\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty3\tlog\tlog.0000000000000000000 refcount=2} active=true starting pos=431
[junit4:junit4]   2> 14775 T1636 C128 P51366 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14777 T1636 C128 P51366 oass.SolrIndexSearcher.<init> Opening Searcher@11612e8 main
[junit4:junit4]   2> 14779 T1636 C128 P51366 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14779 T1612 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11612e8 main{StandardDirectoryReader(segments_2:3:nrt _0(4.1):C1)}
[junit4:junit4]   2> 14779 T1636 C128 P51366 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=0 deletes=0 deleteByQuery=0 errors=0 positionOfStart=431}
[junit4:junit4]   2> 14780 T1613 C128 P51366 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 14783 T1613 C128 P51366 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 14839 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14849 T1611 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14850 T1630 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14850 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14850 T1632 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14852 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14852 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14852 T1625 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 15771 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C130_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:51372_solr, base_url=http://127.0.0.1:51372/solr}
[junit4:junit4]   2> 16833 T1627 C130 P51372 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 16833 T1627 C130 P51372 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 16833 T1627 C130 P51372 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:51362/solr/collection1/. core=collection1
[junit4:junit4]   2> 16833 T1627 C130 P51372 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16842 T1596 C131 P51362 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16849 T1596 C131 P51362 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a4dfd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 16850 T1596 C131 P51362 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 16852 T1596 C131 P51362 oass.SolrIndexSearcher.<init> Opening Searcher@423e1a main
[junit4:junit4]   2> 16853 T1596 C131 P51362 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16853 T1600 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@423e1a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 16854 T1596 C131 P51362 /update {waitSearcher=true&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 12
[junit4:junit4]   2> 16855 T1627 C130 P51372 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 16855 T1627 C130 P51372 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 16856 T1593 C131 P51362 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 16857 T1593 C131 P51362 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16863 T1627 C130 P51372 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1067cdc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 16866 T1627 C130 P51372 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 16866 T1627 C130 P51372 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16881 T1627 C130 P51372 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1067cdc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1067cdc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 16881 T1627 C130 P51372 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 16888 T1627 C130 P51372 oass.SolrIndexSearcher.<init> Opening Searcher@1fcfa82 main
[junit4:junit4]   2> 16888 T1627 C130 P51372 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16889 T1626 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fcfa82 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 16889 T1627 C130 P51372 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 16889 T1627 C130 P51372 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 16892 T1627 C130 P51372 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 16958 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17240 T1574 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17250 T1611 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17250 T1573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17251 T1587 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17251 T1625 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17251 T1630 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17251 T1599 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17251 T1632 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 18146 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18151 T1565 C132 P51354 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 18267 T1565 C132 P51354 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1671ec; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1671ec; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0_Block_0.pos, _0_Block_0.tip, _0_Block_0.tim, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0_MockRandom_0.tib, _0.fnm, _0_Block_0.doc, _0_nrm.cfs, _0_Direct_0.frq, _0_MockRandom_0.skp, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tiv, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Lucene40WithOrds_0.prx, _0_Direct_0.prx, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 18268 T1565 C132 P51354 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 18292 T1565 C132 P51354 oass.SolrIndexSearcher.<init> Opening Searcher@13cf220 main
[junit4:junit4]   2> 18293 T1565 C132 P51354 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 18295 T1576 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13cf220 main{StandardDirectoryReader(segments_2:3 _0(4.1):C1)}
[junit4:junit4]   2> 18297 T1565 C132 P51354 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 146
[junit4:junit4]   2> 18300 T1594 C131 P51362 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 18309 T1594 C131 P51362 oass.SolrIndexSearcher.<init> Opening Searcher@109eef7 main
[junit4:junit4]   2> 18310 T1594 C131 P51362 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 18311 T1600 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@109eef7 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 18312 T1594 C131 P51362 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:51358/solr/collection1/, StdNode: http://127.0.0.1:51366/solr/collection1/, StdNode: http://127.0.0.1:51372/solr/collection1/]
[junit4:junit4]   2> 18314 T1581 C129 P51358 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2>  C128_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:51366_solr, base_url=http://127.0.0.1:51366/solr}
[junit4:junit4]   2> 18314 T1603 C128 P51366 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 18318 T1603 C128 P51366 oass.SolrIndexSearcher.<init> Opening Searcher@53e5ab main
[junit4:junit4]   2> 18318 T1603 C128 P51366 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 18322 T1612 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@53e5ab main{StandardDirectoryReader(segments_2:3:nrt _0(4.1):C1)}
[junit4:junit4]   2> 18322 T1603 C128 P51366 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&expungeDeletes=false&softCommit=false&version=2} {commit=} 0 8
[junit4:junit4]   2> 18327 T1581 C129 P51358 oass.SolrIndexSearcher.<init> Opening Searcher@18550b8 main
[junit4:junit4]   2> 18328 T1581 C129 P51358 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 18329 T1588 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18550b8 main{StandardDirectoryReader(segments_2:3 _0(4.1):C1)}
[junit4:junit4]   2> 18329 T1581 C129 P51358 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&expungeDeletes=false&softCommit=false&version=2} {commit=} 0 15
[junit4:junit4]   2>  C130_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:51372_solr, base_url=http://127.0.0.1:51372/solr}
[junit4:junit4]   2> 18335 T1617 C130 P51372 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 18344 T1617 C130 P51372 oass.SolrIndexSearcher.<init> Opening Searcher@1f50ed4 main
[junit4:junit4]   2> 18344 T1617 C130 P51372 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 18346 T1626 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f50ed4 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 18347 T1617 C130 P51372 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&expungeDeletes=false&softCommit=false&version=2} {commit=} 0 12
[junit4:junit4]   2> 18347 T1594 C131 P51362 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 47
[junit4:junit4]   2> 18355 T1566 C132 P51354 REQ /select {fl=id,score&shard.url=127.0.0.1:51354/solr/collection1/&NOW=1348583234240&start=0&q=*:*&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 18358 T1566 C132 P51354 REQ /select {shard.url=127.0.0.1:51354/solr/collection1/&NOW=1348583234240&q=*:*&ids=1&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18360 T1565 C132 P51354 REQ /select {wt=javabin&q=*:*&version=2} hits=1 status=0 QTime=10 
[junit4:junit4]   2> 18362 T1553 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51347/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@c821c4
[junit4:junit4]   2> 18363 T1553 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 18363 T1640 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51347
[junit4:junit4]   2> 18365 T1640 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51347, initiating session
[junit4:junit4]   2> 18366 T1555 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51396
[junit4:junit4]   2> 18366 T1555 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51396
[junit4:junit4]   2> 18370 T1557 oazs.NIOServerCnxn.finishSessionInit Established session 0x139fdd2d3280009 with negotiated timeout 10000 for client /127.0.0.1:51396
[junit4:junit4]   2> 18370 T1640 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51347, sessionid = 0x139fdd2d3280009, negotiated timeout = 10000
[junit4:junit4]   2> 18371 T1641 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c821c4 name:ZooKeeperConnection Watcher:127.0.0.1:51347/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 18371 T1553 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 18373 T1553 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 18376 T1558 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139fdd2d3280009
[junit4:junit4]   2> 18378 T1553 oaz.ZooKeeper.close Session: 0x139fdd2d3280009 closed
[junit4:junit4]   2> 18378 T1555 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51396 which had sessionid 0x139fdd2d3280009
[junit4:junit4]   2> 18378 T1641 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 18380 T1583 C129 P51358 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 18383 T1595 C131 P51362 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 18388 T1597 C131 P51362 REQ /select {fl=id,score&shard.url=127.0.0.1:51362/solr/collection1/|127.0.0.1:51372/solr/collection1/&NOW=1348583234275&start=0&q=*:*&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 18388 T1580 C129 P51358 REQ /select {fl=id,score&shard.url=127.0.0.1:51358/solr/collection1/|127.0.0.1:51366/solr/collection1/&NOW=1348583234275&start=0&q=*:*&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 18392 T1580 C129 P51358 REQ /select {shard.url=127.0.0.1:51358/solr/collection1/|127.0.0.1:51366/solr/collection1/&NOW=1348583234275&q=*:*&ids=1&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18394 T1585 C129 P51358 REQ /select {wt=javabin&q=*:*&version=2} hits=1 status=0 QTime=9 
[junit4:junit4]   2> 18400 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[2 (1414092013475725312)]} 0 3
[junit4:junit4]   2> 18416 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 18417 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=11 
[junit4:junit4]   2> 18417 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[2]} 0 13
[junit4:junit4]   2> 18422 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[3 (1414092013500891136)]} 0 1
[junit4:junit4]   2> 18432 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18432 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 18434 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[3]} 0 8
[junit4:junit4]   2> 18437 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[4 (1414092013516619776)]} 0 1
[junit4:junit4]   2> 18452 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 18454 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[4 (1414092013521862656)]} 0 13
[junit4:junit4]   2> 18458 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[5 (1414092013538639872)]} 0 1
[junit4:junit4]   2> 18465 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18466 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[5 (1414092013541785600)]} 0 6
[junit4:junit4]   2> 18470 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[6 (1414092013550174208)]} 0 2
[junit4:junit4]   2> 18480 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18481 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 18481 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[6]} 0 9
[junit4:junit4]   2> 18486 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[7 (1414092013568000000)]} 0 1
[junit4:junit4]   2> 18494 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18498 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[7 (1414092013572194304)]} 0 9
[junit4:junit4]   2> 18502 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[8 (1414092013584777216)]} 0 1
[junit4:junit4]   2> 18515 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 18516 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[8 (1414092013587922944)]} 0 12
[junit4:junit4]   2> 18523 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[9 (1414092013607845888)]} 0 1
[junit4:junit4]   2> 18533 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18533 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 18534 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[9]} 0 8
[junit4:junit4]   2> 18539 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[10 (1414092013623574528)]} 0 1
[junit4:junit4]   2> 18555 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18556 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 18556 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[10]} 0 14
[junit4:junit4]   2> 18591 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[11 (1414092013646643200)]} 0 31
[junit4:junit4]   2> 18599 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18599 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[11 (1414092013683343360)]} 0 4
[junit4:junit4]   2> 18606 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[12 (1414092013691731968)]} 0 3
[junit4:junit4]   2> 18618 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18618 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[12 (1414092013699072000)]} 0 8
[junit4:junit4]   2> 18622 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[13 (1414092013710606336)]} 0 1
[junit4:junit4]   2> 18629 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18630 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[13 (1414092013714800640)]} 0 5
[junit4:junit4]   2> 18634 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[14 (1414092013723189248)]} 0 1
[junit4:junit4]   2> 18641 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18641 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[14 (1414092013726334976)]} 0 5
[junit4:junit4]   2> 18647 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[15 (1414092013734723584)]} 0 3
[junit4:junit4]   2> 18654 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18654 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[15 (1414092013739966464)]} 0 5
[junit4:junit4]   2> 18658 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[16 (1414092013748355072)]} 0 1
[junit4:junit4]   2> 18664 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18666 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[16 (1414092013752549376)]} 0 5
[junit4:junit4]   2> 18677 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[17 (1414092013764083712)]} 0 5
[junit4:junit4]   2> 18691 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 18693 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[17 (1414092013775618048)]} 0 12
[junit4:junit4]   2> 18698 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[100 (1414092013790298112)]} 0 1
[junit4:junit4]   2> 18773 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=30 
[junit4:junit4]   2> 18775 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=73 
[junit4:junit4]   2> 18775 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[100]} 0 75
[junit4:junit4]   2> 18780 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[101 (1414092013876281344)]} 0 1
[junit4:junit4]   2> 18797 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 18800 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=13 
[junit4:junit4]   2> 18801 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[101]} 0 18
[junit4:junit4]   2> 18807 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[102 (1414092013902495744)]} 0 3
[junit4:junit4]   2> 18815 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 18817 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[102 (1414092013908787200)]} 0 8
[junit4:junit4]   2> 18853 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[103 (1414092013919272960)]} 0 33
[junit4:junit4]   2> 18876 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18878 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[103 (1414092013958070272)]} 0 22
[junit4:junit4]   2> 18912 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[104 (1414092014011547648)]} 0 4
[junit4:junit4]   2> 18927 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 18928 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 18929 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[104]} 0 14
[junit4:junit4]   2> 18933 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[105 (1414092014036713472)]} 0 1
[junit4:junit4]   2> 18999 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=29 
[junit4:junit4]   2> 19001 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[105 (1414092014040907776)]} 0 65
[junit4:junit4]   2> 19006 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[106 (1414092014113259520)]} 0 1
[junit4:junit4]   2> 19020 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 19022 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[106 (1414092014117453824)]} 0 13
[junit4:junit4]   2> 19025 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[107 (1414092014133182464)]} 0 1
[junit4:junit4]   2> 19036 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19037 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 19038 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[107]} 0 10
[junit4:junit4]   2> 19043 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[108 (1414092014152056832)]} 0 1
[junit4:junit4]   2> 19052 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19053 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[108 (1414092014157299712)]} 0 7
[junit4:junit4]   2> 19058 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[109 (1414092014167785472)]} 0 1
[junit4:junit4]   2> 19067 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19068 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[109 (1414092014171979776)]} 0 7
[junit4:junit4]   2> 19072 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[110 (1414092014182465536)]} 0 1
[junit4:junit4]   2> 19081 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19082 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[110 (1414092014186659840)]} 0 7
[junit4:junit4]   2> 19088 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[111 (1414092014199242752)]} 0 1
[junit4:junit4]   2> 19102 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19103 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[111 (1414092014209728512)]} 0 6
[junit4:junit4]   2> 19109 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[112 (1414092014221262848)]} 0 1
[junit4:junit4]   2> 19119 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 19122 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[112 (1414092014225457152)]} 0 10
[junit4:junit4]   2> 19149 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[113 (1414092014240137216)]} 0 23
[junit4:junit4]   2> 19162 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19163 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 19163 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[113]} 0 9
[junit4:junit4]   2> 19169 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[114 (1414092014284177408)]} 0 2
[junit4:junit4]   2> 19178 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19179 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[114 (1414092014290468864)]} 0 5
[junit4:junit4]   2> 19183 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[115 (1414092014299906048)]} 0 0
[junit4:junit4]   2> 19192 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19193 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[115 (1414092014305148928)]} 0 7
[junit4:junit4]   2> 19198 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[116 (1414092014314586112)]} 0 1
[junit4:junit4]   2> 19208 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19210 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 19211 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[116]} 0 11
[junit4:junit4]   2> 19214 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[117 (1414092014331363328)]} 0 1
[junit4:junit4]   2> 19236 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 19237 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 19237 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[117]} 0 12
[junit4:junit4]   2> 19242 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[118 (1414092014361772032)]} 0 1
[junit4:junit4]   2> 19291 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=19 
[junit4:junit4]   2> 19292 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=38 
[junit4:junit4]   2> 19293 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[118]} 0 43
[junit4:junit4]   2> 19295 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[119 (1414092014417346560)]} 0 0
[junit4:junit4]   2> 19303 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19303 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[119 (1414092014422589440)]} 0 3
[junit4:junit4]   2> 19307 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[120 (1414092014429929472)]} 0 0
[junit4:junit4]   2> 19315 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19316 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[120 (1414092014434123776)]} 0 5
[junit4:junit4]   2> 19320 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[121 (1414092014443560960)]} 0 0
[junit4:junit4]   2> 19330 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19331 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 19332 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[121]} 0 9
[junit4:junit4]   2> 19335 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[122 (1414092014459289600)]} 0 0
[junit4:junit4]   2> 19346 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19349 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 19349 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[122]} 0 11
[junit4:junit4]   2> 19366 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[123 (1414092014477115392)]} 0 14
[junit4:junit4]   2> 19375 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19376 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 19377 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[123]} 0 8
[junit4:junit4]   2> 19382 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[124 (1414092014507524096)]} 0 1
[junit4:junit4]   2> 19390 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19390 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[124 (1414092014512766976)]} 0 4
[junit4:junit4]   2> 19395 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[125 (1414092014521155584)]} 0 1
[junit4:junit4]   2> 19405 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19406 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 19406 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[125]} 0 9
[junit4:junit4]   2> 19411 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[126 (1414092014537932800)]} 0 1
[junit4:junit4]   2> 19418 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19419 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[126 (1414092014541078528)]} 0 6
[junit4:junit4]   2> 19424 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[127 (1414092014551564288)]} 0 1
[junit4:junit4]   2> 19462 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=14 
[junit4:junit4]   2> 19463 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=32 
[junit4:junit4]   2> 19464 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[127]} 0 36
[junit4:junit4]   2> 19469 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[128 (1414092014598750208)]} 0 1
[junit4:junit4]   2> 19481 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 19483 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[128 (1414092014605041664)]} 0 9
[junit4:junit4]   2> 19486 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[129 (1414092014616576000)]} 0 1
[junit4:junit4]   2> 19497 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19497 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 19498 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[129]} 0 8
[junit4:junit4]   2> 19500 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[130 (1414092014632304640)]} 0 0
[junit4:junit4]   2> 19512 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19513 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 19513 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[130]} 0 8
[junit4:junit4]   2> 19516 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[131 (1414092014649081856)]} 0 0
[junit4:junit4]   2> 19537 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19540 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=11 
[junit4:junit4]   2> 19540 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[131]} 0 20
[junit4:junit4]   2> 19544 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[132 (1414092014677393408)]} 0 1
[junit4:junit4]   2> 19554 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 19555 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 19556 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[132]} 0 9
[junit4:junit4]   2> 19573 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[133 (1414092014694170624)]} 0 14
[junit4:junit4]   2> 19582 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19584 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 19585 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[133]} 0 8
[junit4:junit4]   2> 19589 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[134 (1414092014723530752)]} 0 2
[junit4:junit4]   2> 19596 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19598 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[134 (1414092014729822208)]} 0 5
[junit4:junit4]   2> 19602 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[135 (1414092014738210816)]} 0 1
[junit4:junit4]   2> 19610 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19611 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 19613 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[135]} 0 9
[junit4:junit4]   2> 19616 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[136 (1414092014752890880)]} 0 1
[junit4:junit4]   2> 19623 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19625 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[136 (1414092014756036608)]} 0 7
[junit4:junit4]   2> 19630 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[137 (1414092014767570944)]} 0 1
[junit4:junit4]   2> 19637 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19638 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[137 (1414092014770716672)]} 0 6
[junit4:junit4]   2> 19642 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[138 (1414092014781202432)]} 0 0
[junit4:junit4]   2> 19652 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19655 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[138 (1414092014786445312)]} 0 8
[junit4:junit4]   2> 19664 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[139 (1414092014802173952)]} 0 2
[junit4:junit4]   2> 19675 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19677 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[139 (1414092014810562560)]} 0 8
[junit4:junit4]   2> 19683 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[140 (1414092014823145472)]} 0 2
[junit4:junit4]   2> 19692 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19695 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 19696 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[140]} 0 10
[junit4:junit4]   2> 19703 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[141 (1414092014843068416)]} 0 4
[junit4:junit4]   2> 19714 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19714 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[141 (1414092014850408448)]} 0 6
[junit4:junit4]   2> 19721 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[142 (1414092014861942784)]} 0 3
[junit4:junit4]   2> 19768 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=21 
[junit4:junit4]   2> 19769 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=41 
[junit4:junit4]   2> 19770 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[142]} 0 45
[junit4:junit4]   2> 19793 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[143 (1414092014919614464)]} 0 19
[junit4:junit4]   2> 19805 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19806 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[143 (1414092014944780288)]} 0 9
[junit4:junit4]   2> 19811 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[144 (1414092014955266048)]} 0 3
[junit4:junit4]   2> 19818 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19819 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[144 (1414092014962606080)]} 0 4
[junit4:junit4]   2> 19821 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[145 (1414092014968897536)]} 0 0
[junit4:junit4]   2> 19830 T1617 C130 P51372 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19833 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[145 (1414092014972043264)]} 0 9
[junit4:junit4]   2> 19838 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[146 (1414092014985674752)]} 0 2
[junit4:junit4]   2> 19897 T1603 C128 P51366 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=23 
[junit4:junit4]   2> 19900 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[146 (1414092014989869056)]} 0 59
[junit4:junit4]   2> 19904 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[147 (1414092015053832192)]} 0 2
[junit4:junit4]   2> 19915 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 19916 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 19917 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[147]} 0 9
[junit4:junit4]   2> 19921 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[148 (1414092015071657984)]} 0 2
[junit4:junit4]   2> 19940 T1603 C128 P51366 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51358/solr/collection1/&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 19941 T1581 C129 P51358 REQ /update {distrib.from=http://127.0.0.1:51362/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=15 
[junit4:junit4]   2> 19943 T1594 C131 P51362 /update {wt=javabin&version=2} {add=[148]} 0 20
[junit4:junit4]   2> 19948 T1565 C132 P51354 /update {wt=javabin&version=2} {add=[149 (1414092015101018112)]} 0 2
[junit4:junit4]   2> 19962 T1617 C130 P51372 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:51362/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 19965 T1591 C131 P51362 REQ /update {distrib.from=http://127.0.0.1:51358/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 19966 T1585 C129 P51358 /update {wt=javabin&version=2} {add=[149]} 0 13
[junit4:junit4]   2> 19967 T1553 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):600
[junit4:junit4]   2> 19969 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19974 T1565 C132 P51354 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 20377 T1565 C132 P51354 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1671ec; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0_Block_0.pos, _0_Block_0.tip, _0_Block_0.tim, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0_MockRandom_0.tib, _0.fnm, _0_Block_0.doc, _0_nrm.cfs, _0_Direct_0.frq, _0_MockRandom_0.skp, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tiv, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Lucene40WithOrds_0.prx, _0_Direct_0.prx, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1348583215895\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1671ec; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_5_nrm.cfe, _2_Direct_0.tip, _2_Block_0.pos, _2_Direct_0.tim, _7.si, _6_Direct_0.tip, _6_Direct_0.tim, _3.fdt, _5_Lucene40WithOrds_0.tib, _1_MockRandom_0.pos, _7.fnm, _6_Block_0.pos, _0_Lucene40WithOrds_0.tii, _5_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _1_Lucene40WithOrds_0.tib, _7_Lucene40WithOrds_0.frq, _1_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.tib, _1_Block_0.pos, _1_Block_0.tip, _1_Lucene40WithOrds_0.frq, _5_Direct_0.prx, _1_Block_0.tim, _1_MockRandom_0.tip, _2.fdt, _7.fdx, _1_MockRandom_0.tim, _1_MockRandom_0.sd, _2.fdx, _7.fdt, _0_MockRandom_0.skp, _3_Block_0.pos, _3_Block_0.doc, _1.fdx, _0_MockRandom_0.frq, _5_nrm.cfs, _1.fdt, _7_Block_0.pos, _1_MockRandom_0.doc, _3.si, _6.fdt, _5_Lucene40WithOrds_0.prx, _2.si, _5_Direct_0.tim, _4_Lucene40WithOrds_0.tib, _2_MockRandom_0.tii, _4_Lucene40WithOrds_0.tii, _2_Block_0.tim, _6.fdx, _2_Block_0.tip, _1_Lucene40WithOrds_0.prx, _3.fnm, _5_Block_0.tim, _2_nrm.cfe, _2_MockRandom_0.pyl, _2_MockRandom_0.tib, _2_nrm.cfs, _5_Block_0.doc, _5_Direct_0.tip, _4_Block_0.pos, _3_Block_0.tip, _4_Block_0.tip, _2_Lucene40WithOrds_0.frq, _1_Direct_0.prx, _6_Direct_0.prx, _2.fnm, _3.fdx, _2_MockRandom_0.sd, _2_MockRandom_0.skp, _1.si, _6.si, _6.fnm, _0_Direct_0.tip, _0_Lucene40WithOrds_0.prx, _0_Direct_0.prx, _3_Lucene40WithOrds_0.prx, _0_Direct_0.tim, _2_MockRandom_0.doc, _1_Direct_0.frq, _3_nrm.cfe, _0_Block_0.tip, _3_Direct_0.frq, _5.fdt, _0_Block_0.tim, _3_Lucene40WithOrds_0.tii, _7_Block_0.tim, _6_Lucene40WithOrds_0.prx, _4_nrm.cfe, _4.fnm, _7_Block_0.tip, _1.fnm, _3_Lucene40WithOrds_0.tib, _4.fdx, _3_Block_0.tim, _4_Direct_0.frq, _7_Direct_0.tim, _1_Direct_0.tim, _3_Direct_0.prx, _7_Direct_0.tip, _1_Block_0.doc, _1_Direct_0.tip, _3_Direct_0.tip, _6_Lucene40WithOrds_0.frq, _4_Block_0.tim, _0.fnm, _6_Block_0.doc, _4_Direct_0.tim, _7_Lucene40WithOrds_0.tib, _5_Direct_0.frq, _6_Lucene40WithOrds_0.tii, _0_Direct_0.frq, _5.si, _5_Lucene40WithOrds_0.frq, _3_nrm.cfs, _7_Lucene40WithOrds_0.tii, _4_Lucene40WithOrds_0.prx, _6_Lucene40WithOrds_0.tib, _4_Direct_0.prx, _0_MockRandom_0.doc, _7_Direct_0.frq, _4_nrm.cfs, _0_MockRandom_0.pos, _4_Direct_0.tip, _1_MockRandom_0.frq, _5_Block_0.tip, _3_Direct_0.tim, _7_Block_0.doc, _0_Block_0.pos, _4_Lucene40WithOrds_0.frq, _4_Block_0.doc, _6_nrm.cfs, _2_Lucene40WithOrds_0.prx, _7_nrm.cfs, _5_Block_0.pos, _7_Lucene40WithOrds_0.prx, _2_MockRandom_0.pos, _0.si, _1_nrm.cfs, _0_MockRandom_0.tib, _5.fnm, _0_Block_0.doc, _7_nrm.cfe, _6_nrm.cfe, _4.si, _4.fdt, _1_MockRandom_0.skp, _0_nrm.cfs, _3_Lucene40WithOrds_0.frq, _1_nrm.cfe, _2_Direct_0.prx, _2_Lucene40WithOrds_0.tii, _0_MockRandom_0.sd, _1_MockRandom_0.pyl, _0_MockRandom_0.tiv, _7_Direct_0.prx, _6_Block_0.tip, _0_nrm.cfe, _5.fdx, _2_MockRandom_0.frq, _0.fdx, _2_Direct_0.frq, _2_Lucene40WithOrds_0.tib, _6_Block_0.tim, _0_MockRandom_0.pyl, _2_Block_0.doc, _6_Direct_0.frq, _0.fdt, segments_3]
[junit4:junit4]   2> 20378 T1565 C132 P51354 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 20440 T1565 C132 P51354 oass.SolrIndexSearcher.<init> Opening Searcher@e3408d main
[junit4:junit4]   2> 20441 

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

.java:96)
[junit4:junit4]    > 	at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4] Completed in 180.89s, 1 test, 1 failure <<< FAILURES!

[...truncated 1 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 0 T1749 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=sl_SI, timezone=America/Argentina/Mendoza
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_35 (32-bit)/cpus=2,threads=1,free=87548600,total=141238272
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TermsComponentTest, TestFastWriter, ScriptEngineTest, ResponseLogComponentTest, TestPHPSerializedResponseWriter, FieldMutatingUpdateProcessorTest, TestPhraseSuggestions, IndexReaderFactoryTest, MultiTermTest, NoCacheHeaderTest, BinaryUpdateRequestHandlerTest, TestBinaryResponseWriter, FastVectorHighlighterTest, SolrCmdDistributorTest, FieldAnalysisRequestHandlerTest, EchoParamsTest, TestQueryTypes, SuggesterFSTTest, BasicZkTest, TestDistributedGrouping, DistanceFunctionTest, TestIBSimilarityFactory, LoggingHandlerTest, FileBasedSpellCheckerTest, SolrPluginUtilsTest, TestLFUCache, ReturnFieldsTest, ZkControllerTest, SpellPossibilityIteratorTest, StatsComponentTest, TestBinaryField, SOLR749Test, TestRemoteStreaming, TestJoin, TestStressVersions, TestCSVLoader, DirectSolrSpellCheckerTest, TestJmxMonitoredMap, StandardRequestHandlerTest, JSONWriterTest, StatelessScriptUpdateProcessorFactoryTest, TestSearchPerf, DateFieldTest, PingRequestHandlerTest, PolyFieldTest, LeaderElectionIntegrationTest, RequiredFieldsTest, SolrInfoMBeanTest, TestCoreContainer, TestFiltering, TestRTGBase, TestSystemIdResolver, TestWordDelimiterFilterFactory, TestPluginEnable, SpatialFilterTest, TestUpdate, TestRangeQuery, TestGroupingSearch, TestWriterPerf, CoreContainerCoreInitFailuresTest, TestPseudoReturnFields, LukeRequestHandlerTest, OpenExchangeRatesOrgProviderTest, TestValueSourceCache, SignatureUpdateProcessorFactoryTest, TestBadConfig, CopyFieldTest, TestLuceneMatchVersion, TestDistributedSearch, TestConfig, XsltUpdateRequestHandlerTest, TestArbitraryIndexDir, RequestHandlersTest, IndexBasedSpellCheckerTest, DocumentBuilderTest, SyncSliceTest, PrimUtilsTest, TestNumberUtils, HighlighterTest, TestMergePolicyConfig, TestDFRSimilarityFactory, TestOmitPositions, TestExtendedDismaxParser, SoftAutoCommitTest, CurrencyFieldTest, TestRandomFaceting, PreAnalyzedFieldTest, TestReplicationHandler, NumericFieldsTest, DefaultValueUpdateProcessorTest, TestRealTimeGet, PathHierarchyTokenizerFactoryTest, TestSuggestSpellingConverter, PrimitiveFieldTypeTest, SuggesterTest, SpellCheckCollatorTest, BasicFunctionalityTest, DOMUtilTest, CacheHeaderTest, TermVectorComponentTest, TestQuerySenderListener, MBeansHandlerTest, WordBreakSolrSpellCheckerTest, OutputWriterTest, XmlUpdateRequestHandlerTest, TestDefaultSimilarityFactory, TestLRUCache, TestBM25SimilarityFactory, TestSolr4Spatial, FileUtilsTest, BasicDistributedZk2Test, BasicDistributedZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZkTest -Dtests.seed=48BE871FDAA89ABB -Dtests.slow=true -Dtests.locale=sl_SI -Dtests.timezone=America/Argentina/Mendoza -Dtests.file.encoding=Cp1252
[junit4:junit4] ERROR   0.00s | BasicDistributedZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([48BE871FDAA89ABB]:0)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.useFactory(SolrTestCaseJ4.java:106)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractDistribZkTestBase.beforeThisClass(AbstractDistribZkTestBase.java:47)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:677)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4] Completed in 0.05s, 0 tests, 1 failure <<< FAILURES!

[...truncated 356 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:32: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:179: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\module-build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1142: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:815: There were test failures: 231 suites, 954 tests, 2 suite-level errors, 12 ignored (7 assumptions)

Total time: 39 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.6.0_35 -server -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message