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.7.0_07) - Build # 1367 - Failure!
Date Tue, 30 Oct 2012 17:08:12 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/1367/
Java: 32bit/jdk1.7.0_07 -server -XX:+UseConcMarkSweepGC

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

Error Message:
Leader never changed

Stack Trace:
java.lang.AssertionError: Leader never changed
	at __randomizedtesting.SeedInfo.seed([F6153C53DBD53F99:77F3B24BAC8A5FA5]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.BasicDistributedZkTest.testCoreUnloadAndLeaders(BasicDistributedZkTest.java:446)
	at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:335)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java: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:722)




Build Log:
[...truncated 8664 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 3 T2005 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-BasicDistributedZkTest-1351616350830
[junit4:junit4]   2> 6 T2005 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 10 T2006 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 11 T2006 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 12 T2006 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 119 T2005 oasc.ZkTestServer.run start zk server on port:55223
[junit4:junit4]   2> 119 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4b912a
[junit4:junit4]   2> 121 T2011 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 121 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 122 T2011 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 122 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55226
[junit4:junit4]   2> 123 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55226
[junit4:junit4]   2> 123 T2009 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 128 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760000 with negotiated timeout 10000 for client /127.0.0.1:55226
[junit4:junit4]   2> 128 T2011 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760000, negotiated timeout = 10000
[junit4:junit4]   2> 128 T2012 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b912a name:ZooKeeperConnection Watcher:127.0.0.1:55223 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 128 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 128 T2005 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 133 T2010 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ab29cca760000
[junit4:junit4]   2> 134 T2005 oaz.ZooKeeper.close Session: 0x13ab29cca760000 closed
[junit4:junit4]   2> 135 T2007 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x13ab29cca760000, likely client has closed socket
[junit4:junit4]   2> 135 T2012 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 135 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@f6f27a
[junit4:junit4]   2> 136 T2007 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:55226 which had sessionid 0x13ab29cca760000
[junit4:junit4]   2> 137 T2013 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 137 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 138 T2013 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 138 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55229
[junit4:junit4]   2> 138 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55229
[junit4:junit4]   2> 140 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760001 with negotiated timeout 10000 for client /127.0.0.1:55229
[junit4:junit4]   2> 140 T2013 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760001, negotiated timeout = 10000
[junit4:junit4]   2> 142 T2014 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f6f27a name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 142 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 142 T2005 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 147 T2005 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 150 T2005 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 154 T2005 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 158 T2005 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> 159 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 167 T2005 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> 167 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 171 T2005 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> 172 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 185 T2005 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> 186 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 191 T2005 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> 191 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 195 T2005 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> 195 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 199 T2005 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> 199 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 204 T2005 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> 204 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 208 T2005 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> 208 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 212 T2005 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> 214 T2005 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 217 T2010 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ab29cca760001
[junit4:junit4]   2> 218 T2014 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 218 T2007 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x13ab29cca760001, likely client has closed socket
[junit4:junit4]   2> 219 T2007 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:55229 which had sessionid 0x13ab29cca760001
[junit4:junit4]   2> 218 T2005 oaz.ZooKeeper.close Session: 0x13ab29cca760001 closed
[junit4:junit4]   2> 221 T2005 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 226 T2005 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55230
[junit4:junit4]   2> 226 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 227 T2005 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> 227 T2005 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> 260 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 260 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 262 T2005 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> 262 T2005 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> 262 T2005 oasc.CoreContainer.<init> New CoreContainer 2513620
[junit4:junit4]   2> 263 T2005 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> 263 T2005 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> 287 T2005 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 300 T2005 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55223/solr
[junit4:junit4]   2> 301 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@bd103f
[junit4:junit4]   2> 302 T2024 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 302 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 304 T2024 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 304 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55235
[junit4:junit4]   2> 305 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55235
[junit4:junit4]   2> 307 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760002 with negotiated timeout 5000 for client /127.0.0.1:55235
[junit4:junit4]   2> 307 T2024 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760002, negotiated timeout = 5000
[junit4:junit4]   2> 307 T2025 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bd103f name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 307 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 309 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 312 T2005 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 315 T2005 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55230_solr
[junit4:junit4]   2> 315 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55230_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55230_solr
[junit4:junit4]   2> 317 T2005 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55230_solr
[junit4:junit4]   2> 320 T2005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 320 T2005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 321 T2005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 321 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 321 T2005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 323 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 323 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 323 T2005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 323 T2005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 324 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 335 T2005 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 341 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 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> 343 T2005 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 347 T2005 oasc.Overseer.start Overseer (id=88579529168519170-127.0.0.1:55230_solr-n_0000000000) starting
[junit4:junit4]   2> 347 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 349 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 350 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 352 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 353 T2005 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 354 T2027 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 356 T2005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 360 T2026 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 365 T2005 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> 366 T2005 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 367 T2005 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 367 T2005 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 368 T2005 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> 372 T2005 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> 373 T2005 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> 425 T2005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 485 T2005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 486 T2005 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 493 T2005 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1059 T2005 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1068 T2005 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1078 T2005 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1096 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1104 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1112 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1114 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1116 T2005 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.BasicDistributedZkTest-1351616350828/control/data\
[junit4:junit4]   2> 1116 T2005 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e9be9
[junit4:junit4]   2> 1118 T2005 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/control/data\index/
[junit4:junit4]   2> 1121 T2005 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1122 T2005 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.BasicDistributedZkTest-1351616350828\control\data\index forceNew:false
[junit4:junit4]   2> 1135 T2005 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.BasicDistributedZkTest-1351616350828\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a36a26; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1135 T2005 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1137 T2005 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1138 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1139 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1139 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1139 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1140 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1140 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1140 T2005 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1141 T2005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1141 T2005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1141 T2005 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1142 T2005 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1142 T2005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1142 T2005 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1142 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1144 T2005 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1144 T2005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1144 T2005 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1145 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1146 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1146 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1146 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1147 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1147 T2005 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1147 T2005 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1148 T2005 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1159 T2005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1162 T2005 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1164 T2005 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> 1169 T2005 oass.SolrIndexSearcher.<init> Opening Searcher@1956ccf main
[junit4:junit4]   2> 1171 T2005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1172 T2005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1172 T2005 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1184 T2005 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1236 T2028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1956ccf main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1238 T2005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1240 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 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> 1245 T2005 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1246 T2005 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55230/solr shardId:control_shard
[junit4:junit4]   2> 1247 T2005 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1257 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 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> 1259 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1259 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1482 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1483 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55230_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55230/solr"}
[junit4:junit4]   2> 1483 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 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> 1497 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1821 T2005 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1822 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1822 T2005 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55230/solr/collection1/
[junit4:junit4]   2> 1823 T2005 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1823 T2005 oasc.SyncStrategy.syncToMe http://127.0.0.1:55230/solr/collection1/ has no replicas
[junit4:junit4]   2> 1823 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55230/solr/collection1/
[junit4:junit4]   2> 1824 T2005 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1830 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2063 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2069 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2117 T2005 oasc.ZkController.register We are http://127.0.0.1:55230/solr/collection1/ and leader is http://127.0.0.1:55230/solr/collection1/
[junit4:junit4]   2> 2117 T2005 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55230/solr
[junit4:junit4]   2> 2117 T2005 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2117 T2005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2120 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2122 T2005 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2122 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2122 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2455 T2005 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2460 T2005 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55236
[junit4:junit4]   2> 2461 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2461 T2005 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-1351616352957
[junit4:junit4]   2> 2462 T2005 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-1351616352957\'
[junit4:junit4]   2> 2492 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2493 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2493 T2005 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-1351616352957
[junit4:junit4]   2> 2493 T2005 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-1351616352957\solr.xml
[junit4:junit4]   2> 2493 T2005 oasc.CoreContainer.<init> New CoreContainer 5916806
[junit4:junit4]   2> 2494 T2005 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-1351616352957\'
[junit4:junit4]   2> 2494 T2005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1351616352957\'
[junit4:junit4]   2> 2518 T2005 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2534 T2005 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55223/solr
[junit4:junit4]   2> 2535 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@5d832
[junit4:junit4]   2> 2536 T2038 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 2536 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2538 T2038 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 2538 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55241
[junit4:junit4]   2> 2539 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55241
[junit4:junit4]   2> 2552 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760003 with negotiated timeout 5000 for client /127.0.0.1:55241
[junit4:junit4]   2> 2552 T2038 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760003, negotiated timeout = 5000
[junit4:junit4]   2> 2552 T2039 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5d832 name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2552 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2553 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2555 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2558 T2005 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55236_solr
[junit4:junit4]   2> 2559 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55236_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55236_solr
[junit4:junit4]   2> 2560 T2005 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55236_solr
[junit4:junit4]   2> 2563 T2025 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2563 T2005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2564 T2005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2564 T2005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2564 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2564 T2005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2564 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2565 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2565 T2005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2566 T2005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2566 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2577 T2005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2583 T2005 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-1351616352957\collection1
[junit4:junit4]   2> 2584 T2005 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2584 T2005 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2585 T2005 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2587 T2005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1351616352957\collection1\'
[junit4:junit4]   2> 2589 T2005 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty1-1351616352957/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2590 T2005 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty1-1351616352957/collection1/lib/README' to classloader
[junit4:junit4]   2> 2633 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2634 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55230_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55230/solr"}
[junit4:junit4]   2> 2642 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2642 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2653 T2005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 2724 T2005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2733 T2005 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2739 T2005 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3363 T2005 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3373 T2005 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3378 T2005 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3393 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3397 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3403 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3404 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3406 T2005 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-1351616352957\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/jetty1\
[junit4:junit4]   2> 3406 T2005 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e9be9
[junit4:junit4]   2> 3408 T2005 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/jetty1\index/
[junit4:junit4]   2> 3408 T2005 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3410 T2005 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.BasicDistributedZkTest-1351616350828\jetty1\index forceNew:false
[junit4:junit4]   2> 3420 T2005 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.BasicDistributedZkTest-1351616350828\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c58a9d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3420 T2005 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3423 T2005 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3424 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3424 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3426 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3426 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3426 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3427 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3427 T2005 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3428 T2005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3428 T2005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3428 T2005 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3429 T2005 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3429 T2005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3430 T2005 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3430 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3430 T2005 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3430 T2005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3431 T2005 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3431 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3431 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3432 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3432 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3432 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3433 T2005 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3433 T2005 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3434 T2005 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3443 T2005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3447 T2005 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3448 T2005 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> 3454 T2005 oass.SolrIndexSearcher.<init> Opening Searcher@1f8f426 main
[junit4:junit4]   2> 3456 T2005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3456 T2005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3457 T2005 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3469 T2005 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3528 T2040 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f8f426 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3531 T2005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3766 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3767 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55236_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55236/solr"}
[junit4:junit4]   2> 3767 T2026 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 3772 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3772 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4097 T2005 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4097 T2005 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55236/solr shardId:shard1
[junit4:junit4]   2> 4099 T2005 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 4110 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760003 type:delete cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 4111 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4112 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4116 T2005 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4117 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4117 T2005 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55236/solr/collection1/
[junit4:junit4]   2> 4117 T2005 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4118 T2005 oasc.SyncStrategy.syncToMe http://127.0.0.1:55236/solr/collection1/ has no replicas
[junit4:junit4]   2> 4118 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55236/solr/collection1/
[junit4:junit4]   2> 4119 T2005 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 4126 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4336 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4347 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4348 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4356 T2005 oasc.ZkController.register We are http://127.0.0.1:55236/solr/collection1/ and leader is http://127.0.0.1:55236/solr/collection1/
[junit4:junit4]   2> 4356 T2005 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55236/solr
[junit4:junit4]   2> 4356 T2005 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4357 T2005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4359 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4361 T2005 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 4361 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4362 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4695 T2005 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4698 T2005 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55242
[junit4:junit4]   2> 4698 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4700 T2005 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-1351616355196
[junit4:junit4]   2> 4701 T2005 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-1351616355196\'
[junit4:junit4]   2> 4733 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4734 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4734 T2005 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-1351616355196
[junit4:junit4]   2> 4734 T2005 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-1351616355196\solr.xml
[junit4:junit4]   2> 4734 T2005 oasc.CoreContainer.<init> New CoreContainer 15613907
[junit4:junit4]   2> 4735 T2005 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-1351616355196\'
[junit4:junit4]   2> 4735 T2005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1351616355196\'
[junit4:junit4]   2> 4758 T2005 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4783 T2005 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55223/solr
[junit4:junit4]   2> 4783 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@8362ef
[junit4:junit4]   2> 4785 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4785 T2050 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 4787 T2050 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 4787 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55247
[junit4:junit4]   2> 4787 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55247
[junit4:junit4]   2> 4799 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760004 with negotiated timeout 5000 for client /127.0.0.1:55247
[junit4:junit4]   2> 4799 T2050 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760004, negotiated timeout = 5000
[junit4:junit4]   2> 4800 T2051 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8362ef name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4800 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4802 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4803 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4806 T2005 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55242_solr
[junit4:junit4]   2> 4807 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55242_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55242_solr
[junit4:junit4]   2> 4808 T2005 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55242_solr
[junit4:junit4]   2> 4811 T2039 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4811 T2025 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4812 T2005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4812 T2005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4814 T2005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4814 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4814 T2005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4815 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4815 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4815 T2005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4816 T2005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4816 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4830 T2005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4837 T2005 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-1351616355196\collection1
[junit4:junit4]   2> 4837 T2005 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4839 T2005 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4839 T2005 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4842 T2005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1351616355196\collection1\'
[junit4:junit4]   2> 4843 T2005 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty2-1351616355196/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4844 T2005 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty2-1351616355196/collection1/lib/README' to classloader
[junit4:junit4]   2> 4900 T2005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 4913 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4914 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55236_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55236/solr"}
[junit4:junit4]   2> 4924 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4925 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4925 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4977 T2005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4979 T2005 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4984 T2005 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5555 T2005 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5563 T2005 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5567 T2005 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5582 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5588 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5593 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5594 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5596 T2005 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-1351616355196\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/jetty2\
[junit4:junit4]   2> 5596 T2005 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e9be9
[junit4:junit4]   2> 5599 T2005 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/jetty2\index/
[junit4:junit4]   2> 5600 T2005 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5601 T2005 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.BasicDistributedZkTest-1351616350828\jetty2\index forceNew:false
[junit4:junit4]   2> 5612 T2005 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.BasicDistributedZkTest-1351616350828\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c2cfb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5612 T2005 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5615 T2005 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5616 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5616 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5617 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5617 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5618 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5618 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5619 T2005 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5619 T2005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5619 T2005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5620 T2005 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5620 T2005 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5620 T2005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5621 T2005 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5623 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5623 T2005 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5623 T2005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5624 T2005 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5624 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5624 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5625 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5625 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5626 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5626 T2005 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5627 T2005 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5627 T2005 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5637 T2005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5642 T2005 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5643 T2005 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> 5650 T2005 oass.SolrIndexSearcher.<init> Opening Searcher@46d523 main
[junit4:junit4]   2> 5652 T2005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5652 T2005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5653 T2005 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5664 T2005 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5718 T2052 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@46d523 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5721 T2005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6053 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6053 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55242_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55242/solr"}
[junit4:junit4]   2> 6064 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6064 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6064 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6282 T2005 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6282 T2005 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55242/solr shardId:shard2
[junit4:junit4]   2> 6283 T2005 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 6292 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760004 type:delete cxid:0x42 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> 6293 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6294 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760004 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6297 T2005 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6297 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6297 T2005 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55242/solr/collection1/
[junit4:junit4]   2> 6298 T2005 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6298 T2005 oasc.SyncStrategy.syncToMe http://127.0.0.1:55242/solr/collection1/ has no replicas
[junit4:junit4]   2> 6298 T2005 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55242/solr/collection1/
[junit4:junit4]   2> 6300 T2005 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 6304 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760004 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6628 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6640 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6640 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6640 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6655 T2005 oasc.ZkController.register We are http://127.0.0.1:55242/solr/collection1/ and leader is http://127.0.0.1:55242/solr/collection1/
[junit4:junit4]   2> 6655 T2005 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55242/solr
[junit4:junit4]   2> 6655 T2005 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 6655 T2005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6659 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6660 T2005 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 6660 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6660 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6998 T2005 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 7002 T2005 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55248
[junit4:junit4]   2> 7002 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7003 T2005 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-1351616357495
[junit4:junit4]   2> 7004 T2005 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-1351616357495\'
[junit4:junit4]   2> 7038 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7038 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7038 T2005 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-1351616357495
[junit4:junit4]   2> 7038 T2005 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-1351616357495\solr.xml
[junit4:junit4]   2> 7039 T2005 oasc.CoreContainer.<init> New CoreContainer 26001600
[junit4:junit4]   2> 7039 T2005 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-1351616357495\'
[junit4:junit4]   2> 7039 T2005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1351616357495\'
[junit4:junit4]   2> 7061 T2005 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 7073 T2005 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55223/solr
[junit4:junit4]   2> 7074 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1191877
[junit4:junit4]   2> 7075 T2062 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 7075 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7076 T2062 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 7076 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55253
[junit4:junit4]   2> 7077 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55253
[junit4:junit4]   2> 7089 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760005 with negotiated timeout 5000 for client /127.0.0.1:55253
[junit4:junit4]   2> 7089 T2062 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760005, negotiated timeout = 5000
[junit4:junit4]   2> 7090 T2063 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1191877 name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7090 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7091 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7093 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7096 T2005 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55248_solr
[junit4:junit4]   2> 7097 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55248_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55248_solr
[junit4:junit4]   2> 7098 T2005 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55248_solr
[junit4:junit4]   2> 7101 T2025 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 7101 T2039 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 7101 T2051 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 7102 T2005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 7102 T2005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7103 T2005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 7103 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7103 T2005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7104 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7104 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7104 T2005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7105 T2005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7105 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 7118 T2005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 7126 T2005 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-1351616357495\collection1
[junit4:junit4]   2> 7126 T2005 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 7127 T2005 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 7127 T2005 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 7130 T2005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1351616357495\collection1\'
[junit4:junit4]   2> 7131 T2005 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty3-1351616357495/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 7133 T2005 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty3-1351616357495/collection1/lib/README' to classloader
[junit4:junit4]   2> 7175 T2005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 7206 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7207 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55242_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55242/solr"}
[junit4:junit4]   2> 7217 T2063 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7217 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7217 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7217 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7248 T2005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 7249 T2005 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7254 T2005 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7876 T2005 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7890 T2005 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7899 T2005 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7916 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7920 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7926 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7929 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7930 T2005 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-1351616357495\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/jetty3\
[junit4:junit4]   2> 7931 T2005 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e9be9
[junit4:junit4]   2> 7934 T2005 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/jetty3\index/
[junit4:junit4]   2> 7935 T2005 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828\jetty3\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7935 T2005 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.BasicDistributedZkTest-1351616350828\jetty3\index forceNew:false
[junit4:junit4]   2> 7948 T2005 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.BasicDistributedZkTest-1351616350828\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a432d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7948 T2005 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7950 T2005 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7952 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7952 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7953 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7953 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7954 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7954 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7954 T2005 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7955 T2005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7955 T2005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7955 T2005 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7956 T2005 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7956 T2005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7956 T2005 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7958 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7958 T2005 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7959 T2005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7959 T2005 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7959 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7960 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7960 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7960 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7961 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7961 T2005 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7961 T2005 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7962 T2005 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7974 T2005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7980 T2005 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7983 T2005 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> 7989 T2005 oass.SolrIndexSearcher.<init> Opening Searcher@fb07b main
[junit4:junit4]   2> 7991 T2005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7991 T2005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7992 T2005 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 8006 T2005 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 8087 T2064 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fb07b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8093 T2005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8409 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8409 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55248_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55248/solr"}
[junit4:junit4]   2> 8417 T2063 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8417 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8417 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8417 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8710 T2005 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8710 T2005 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55248/solr shardId:shard1
[junit4:junit4]   2> 8714 T2005 oasc.ZkController.register We are http://127.0.0.1:55248/solr/collection1/ and leader is http://127.0.0.1:55236/solr/collection1/
[junit4:junit4]   2> 8717 T2005 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55248/solr
[junit4:junit4]   2> 8717 T2005 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 8717 T2005 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C163 name=collection1 org.apache.solr.core.SolrCore@192ee23 url=http://127.0.0.1:55248/solr/collection1 node=127.0.0.1:55248_solr
[junit4:junit4]   2> 8719 T2065 C163 P55248 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 8719 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8720 T2065 C163 P55248 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 8720 T2065 C163 P55248 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8720 T2005 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 8720 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8722 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8723 T2065 C163 P55248 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:55236/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 8723 T2065 C163 P55248 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55248/solr START replicas=[http://127.0.0.1:55236/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 8723 T2065 C163 P55248 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 8723 T2065 C163 P55248 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 8724 T2065 C163 P55248 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 8724 T2065 C163 P55248 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C164 name=collection1 org.apache.solr.core.SolrCore@1f48fd0 url=http://127.0.0.1:55236/solr/collection1 node=127.0.0.1:55236_solr
[junit4:junit4]   2> 8746 T2033 C164 P55236 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 9036 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9037 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55248_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55248/solr"}
[junit4:junit4]   2> 9056 T2063 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 9056 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 9056 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 9056 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 9116 T2005 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 9122 T2005 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55256
[junit4:junit4]   2> 9123 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 9123 T2005 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-1351616359560
[junit4:junit4]   2> 9123 T2005 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-1351616359560\'
[junit4:junit4]   2> 9162 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 9163 T2005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 9163 T2005 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-1351616359560
[junit4:junit4]   2> 9163 T2005 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-1351616359560\solr.xml
[junit4:junit4]   2> 9163 T2005 oasc.CoreContainer.<init> New CoreContainer 3218583
[junit4:junit4]   2> 9165 T2005 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-1351616359560\'
[junit4:junit4]   2> 9165 T2005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1351616359560\'
[junit4:junit4]   2> 9193 T2005 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 9209 T2005 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55223/solr
[junit4:junit4]   2> 9209 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@b180e7
[junit4:junit4]   2> 9210 T2076 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 9210 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 9212 T2076 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 9212 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55261
[junit4:junit4]   2> 9212 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55261
[junit4:junit4]   2> 9216 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760006 with negotiated timeout 5000 for client /127.0.0.1:55261
[junit4:junit4]   2> 9216 T2076 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760006, negotiated timeout = 5000
[junit4:junit4]   2> 9217 T2077 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b180e7 name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 9217 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 9218 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9219 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9222 T2005 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55256_solr
[junit4:junit4]   2> 9223 T2010 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ab29cca760006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55256_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55256_solr
[junit4:junit4]   2> 9224 T2005 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55256_solr
[junit4:junit4]   2> 9228 T2063 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 9229 T2051 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 9229 T2025 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 9229 T2039 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 9230 T2005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 9230 T2005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 9230 T2005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 9230 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 9230 T2005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 9233 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 9233 T2005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 9233 T2005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 9233 T2005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 9234 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 9247 T2005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9264 T2005 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-1351616359560\collection1
[junit4:junit4]   2> 9264 T2005 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9265 T2005 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9265 T2005 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9267 T2005 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1351616359560\collection1\'
[junit4:junit4]   2> 9270 T2005 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty4-1351616359560/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9271 T2005 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty4-1351616359560/collection1/lib/README' to classloader
[junit4:junit4]   2> 9333 T2005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 9408 T2005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9410 T2005 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9416 T2005 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 10095 T2005 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 10106 T2005 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10108 T2005 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10126 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10132 T2005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10138 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10139 T2005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10142 T2005 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-1351616359560\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/jetty4\
[junit4:junit4]   2> 10142 T2005 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e9be9
[junit4:junit4]   2> 10144 T2005 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828/jetty4\index/
[junit4:junit4]   2> 10145 T2005 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZkTest-1351616350828\jetty4\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 10147 T2005 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.BasicDistributedZkTest-1351616350828\jetty4\index forceNew:false
[junit4:junit4]   2> 10159 T2005 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.BasicDistributedZkTest-1351616350828\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43084b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10159 T2005 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10161 T2005 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 10162 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 10162 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 10163 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 10163 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 10163 T2005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 10163 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 10165 T2005 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 10165 T2005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10166 T2005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10166 T2005 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 10166 T2005 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 10167 T2005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10167 T2005 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 10168 T2005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 10168 T2005 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 10169 T2005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10169 T2005 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10169 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10169 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10169 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10171 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10172 T2005 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10173 T2005 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10174 T2005 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 10174 T2005 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 10186 T2005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10191 T2005 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 10193 T2005 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> 10199 T2005 oass.SolrIndexSearcher.<init> Opening Searcher@bf2a8d main
[junit4:junit4]   2> 10202 T2005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10202 T2005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10203 T2005 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 10214 T2005 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 10277 T2078 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bf2a8d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10280 T2005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10293 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10294 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55256_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55256/solr"}
[junit4:junit4]   2> 10301 T2063 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10301 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10302 T2077 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10301 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10302 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10896 T2005 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 10897 T2005 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55256/solr shardId:shard2
[junit4:junit4]   2> 10914 T2005 oasc.ZkController.register We are http://127.0.0.1:55256/solr/collection1/ and leader is http://127.0.0.1:55242/solr/collection1/
[junit4:junit4]   2> 10914 T2005 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55256/solr
[junit4:junit4]   2> 10915 T2005 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 10917 T2005 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C165 name=collection1 org.apache.solr.core.SolrCore@1a18723 url=http://127.0.0.1:55256/solr/collection1 node=127.0.0.1:55256_solr
[junit4:junit4]   2> 10920 T2079 C165 P55256 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 10923 T2079 C165 P55256 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 10923 T2079 C165 P55256 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10923 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10927 T2079 C165 P55256 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:55242/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 10927 T2079 C165 P55256 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55256/solr START replicas=[http://127.0.0.1:55242/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 10929 T2005 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 10930 T2005 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 10931 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10930 T2079 C165 P55256 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 10957 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@125d1ca
[junit4:junit4]   2> 10960 T2079 C165 P55256 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C166 name=collection1 org.apache.solr.core.SolrCore@4a7f8c url=http://127.0.0.1:55242/solr/collection1 node=127.0.0.1:55242_solr
[junit4:junit4]   2> 10962 T2045 C166 P55242 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=2 
[junit4:junit4]   2> 10962 T2079 C165 P55256 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 10962 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10963 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10965 T2081 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 10966 T2079 C165 P55256 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10966 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55256_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55256/solr"}
[junit4:junit4]   2> 10968 T2081 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 10969 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55265
[junit4:junit4]   2> 10969 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55265
[junit4:junit4]   2> 10973 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760007 with negotiated timeout 10000 for client /127.0.0.1:55265
[junit4:junit4]   2> 10973 T2081 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760007, negotiated timeout = 10000
[junit4:junit4]   2> 10974 T2082 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@125d1ca name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10974 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10977 T2005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10979 T2082 oaz.ClientCnxn$EventThread.processEvent SEVERE Error while calling watcher  java.lang.NullPointerException
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkStateReader$2.process(ZkStateReader.java:189)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 10979 T2005 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 10979 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 10980 T2063 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10981 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10981 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10981 T2077 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10981 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10986 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10987 T2005 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):180
[junit4:junit4]   2> 10987 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12217 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C163_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:55248_solr, base_url=http://127.0.0.1:55248/solr}
[junit4:junit4]   2> 12424 T2065 C163 P55248 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 12425 T2065 C163 P55248 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 12425 T2065 C163 P55248 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:55236/solr/collection1/. core=collection1
[junit4:junit4]   2> 12425 T2065 C163 P55248 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12439 T2032 C164 P55236 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12447 T2032 C164 P55236 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.BasicDistributedZkTest-1351616350828\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c58a9d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12448 T2032 C164 P55236 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 12449 T2032 C164 P55236 oass.SolrIndexSearcher.<init> Opening Searcher@22ef3e realtime
[junit4:junit4]   2> 12449 T2032 C164 P55236 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12451 T2032 C164 P55236 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 10
[junit4:junit4]   2> 12452 T2065 C163 P55248 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 12453 T2065 C163 P55248 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 12457 T2035 C164 P55236 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 12457 T2035 C164 P55236 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=2 
[junit4:junit4]   2> 12461 T2065 C163 P55248 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.BasicDistributedZkTest-1351616350828\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a432d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12465 T2065 C163 P55248 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 12466 T2065 C163 P55248 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12478 T2065 C163 P55248 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.BasicDistributedZkTest-1351616350828\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a432d; 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.BasicDistributedZkTest-1351616350828\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a432d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 12478 T2065 C163 P55248 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 12485 T2065 C163 P55248 oass.SolrIndexSearcher.<init> Opening Searcher@12986c5 main
[junit4:junit4]   2> 12485 T2065 C163 P55248 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12496 T2064 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12986c5 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 12497 T2065 C163 P55248 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 12497 T2065 C163 P55248 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 12497 T2065 C163 P55248 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12500 T2065 C163 P55248 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 12834 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12836 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55248_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55248/solr"}
[junit4:junit4]   2> 12851 T2063 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12851 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12852 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12852 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12852 T2077 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 13447 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C165_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:55256_solr, base_url=http://127.0.0.1:55256/solr}
[junit4:junit4]   2> 14660 T2079 C165 P55256 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 14660 T2079 C165 P55256 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 14660 T2079 C165 P55256 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:55242/solr/collection1/. core=collection1
[junit4:junit4]   2> 14660 T2079 C165 P55256 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 14671 T2049 C166 P55242 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14678 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14679 T2049 C166 P55242 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.BasicDistributedZkTest-1351616350828\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c2cfb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14679 T2049 C166 P55242 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 14680 T2049 C166 P55242 oass.SolrIndexSearcher.<init> Opening Searcher@e2db01 realtime
[junit4:junit4]   2> 14680 T2049 C166 P55242 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14680 T2049 C166 P55242 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 9
[junit4:junit4]   2> 14681 T2079 C165 P55256 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 14681 T2079 C165 P55256 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 14685 T2045 C166 P55242 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 14685 T2045 C166 P55242 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 14692 T2079 C165 P55256 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.BasicDistributedZkTest-1351616350828\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43084b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14693 T2079 C165 P55256 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 14693 T2079 C165 P55256 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14709 T2079 C165 P55256 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.BasicDistributedZkTest-1351616350828\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43084b; 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.BasicDistributedZkTest-1351616350828\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43084b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 14709 T2079 C165 P55256 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 14716 T2079 C165 P55256 oass.SolrIndexSearcher.<init> Opening Searcher@b01e55 main
[junit4:junit4]   2> 14717 T2079 C165 P55256 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14719 T2078 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b01e55 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 14719 T2079 C165 P55256 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 14719 T2079 C165 P55256 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 14719 T2079 C165 P55256 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 14722 T2079 C165 P55256 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 15327 T2026 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15328 T2026 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55256_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55256/solr"}
[junit4:junit4]   2> 15335 T2063 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15335 T2025 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15335 T2051 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15335 T2077 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15335 T2039 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15907 T2005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C167 name=collection1 org.apache.solr.core.SolrCore@1df5615 url=http://127.0.0.1:55230/solr/collection1 node=127.0.0.1:55230_solr
[junit4:junit4]   2> 15928 T2021 C167 P55230 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.BasicDistributedZkTest-1351616350828\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a36a26; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 15929 T2021 C167 P55230 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 15930 T2021 C167 P55230 oass.SolrIndexSearcher.<init> Opening Searcher@1fe609f realtime
[junit4:junit4]   2> 15930 T2021 C167 P55230 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1417272483376005120)} 0 10
[junit4:junit4]   2> 15932 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@17969cb
[junit4:junit4]   2> 15934 T2083 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 15934 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 15935 T2083 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 15935 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55274
[junit4:junit4]   2> 15936 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55274
[junit4:junit4]   2> 15941 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760008 with negotiated timeout 10000 for client /127.0.0.1:55274
[junit4:junit4]   2> 15941 T2083 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760008, negotiated timeout = 10000
[junit4:junit4]   2> 15941 T2084 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17969cb name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 15941 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 15942 T2005 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2>  C165_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:55256_solr, base_url=http://127.0.0.1:55256/solr}
[junit4:junit4]   2> 15964 T2071 C165 P55256 oass.SolrIndexSearcher.<init> Opening Searcher@42d317 realtime
[junit4:junit4]   2> 15964 T2071 C165 P55256 REQ /update {update.distrib=FROMLEADER&_version_=-1417272483404316672&update.from=http://127.0.0.1:55242/solr/collection1/&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2>  C163_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:55248_solr, base_url=http://127.0.0.1:55248/solr}
[junit4:junit4]   2> 15970 T2056 C163 P55248 oass.SolrIndexSearcher.<init> Opening Searcher@145c3ed realtime
[junit4:junit4]   2> 15970 T2056 C163 P55248 REQ /update {update.distrib=FROMLEADER&_version_=-1417272483411656704&update.from=http://127.0.0.1:55236/solr/collection1/&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 15972 T2036 C164 P55236 REQ /update {update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=18 
[junit4:junit4]   2> 15973 T2046 C166 P55242 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1417272483404316672)} 0 27
[junit4:junit4]   2> 15980 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[1 (1417272483436822528)]} 0 2
[junit4:junit4]   2> 15994 T2057 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 15995 T2037 C164 P55236 /update {wt=javabin&version=2} {add=[1 (1417272483443113984)]} 0 11
[junit4:junit4]   2> 16000 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[2 (1417272483457794048)]} 0 2
[junit4:junit4]   2> 16013 T2073 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 16014 T2049 C166 P55242 /update {wt=javabin&version=2} {add=[2 (1417272483463036928)]} 0 11
[junit4:junit4]   2> 16020 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[3 (1417272483478765568)]} 0 2
[junit4:junit4]   2> 16028 T2074 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16030 T2044 C166 P55242 /update {wt=javabin&version=2} {add=[3 (1417272483482959872)]} 0 8
[junit4:junit4]   2> 16034 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[4 (1417272483494494208)]} 0 1
[junit4:junit4]   2> 16044 T2058 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16045 T2034 C164 P55236 /update {wt=javabin&version=2} {add=[4 (1417272483499737088)]} 0 7
[junit4:junit4]   2> 16050 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[5 (1417272483511271424)]} 0 1
[junit4:junit4]   2> 16061 T2075 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16062 T2045 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16063 T2032 C164 P55236 /update {wt=javabin&version=2} {add=[5]} 0 9
[junit4:junit4]   2> 16068 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[6 (1417272483529097216)]} 0 2
[junit4:junit4]   2> 16078 T2070 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16079 T2047 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16079 T2035 C164 P55236 /update {wt=javabin&version=2} {add=[6]} 0 9
[junit4:junit4]   2> 16084 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[7 (1417272483545874432)]} 0 2
[junit4:junit4]   2> 16093 T2072 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16094 T2046 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16094 T2033 C164 P55236 /update {wt=javabin&version=2} {add=[7]} 0 8
[junit4:junit4]   2> 16099 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[8 (1417272483562651648)]} 0 1
[junit4:junit4]   2> 16106 T2060 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16108 T2036 C164 P55236 /update {wt=javabin&version=2} {add=[8 (1417272483566845952)]} 0 6
[junit4:junit4]   2> 16111 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[9 (1417272483575234560)]} 0 1
[junit4:junit4]   2> 16118 T2071 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16120 T2049 C166 P55242 /update {wt=javabin&version=2} {add=[9 (1417272483579428864)]} 0 6
[junit4:junit4]   2> 16138 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[10 (1417272483588866048)]} 0 15
[junit4:junit4]   2> 16147 T2059 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16148 T2037 C164 P55236 /update {wt=javabin&version=2} {add=[10 (1417272483607740416)]} 0 8
[junit4:junit4]   2> 16157 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[11 (1417272483619274752)]} 0 5
[junit4:junit4]   2> 16168 T2061 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 16169 T2034 C164 P55236 /update {wt=javabin&version=2} {add=[11 (1417272483627663360)]} 0 9
[junit4:junit4]   2> 16172 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[12 (1417272483640246272)]} 0 1
[junit4:junit4]   2> 16182 T2056 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16183 T2032 C164 P55236 /update {wt=javabin&version=2} {add=[12 (1417272483644440576)]} 0 7
[junit4:junit4]   2> 16187 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[13 (1417272483654926336)]} 0 1
[junit4:junit4]   2> 16200 T2057 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16201 T2035 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 16201 T2044 C166 P55242 /update {wt=javabin&version=2} {add=[13]} 0 11
[junit4:junit4]   2> 16205 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[14 (1417272483674849280)]} 0 0
[junit4:junit4]   2> 16218 T2060 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 16218 T2033 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16219 T2045 C166 P55242 /update {wt=javabin&version=2} {add=[14]} 0 11
[junit4:junit4]   2> 16223 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[15 (1417272483691626496)]} 0 2
[junit4:junit4]   2> 16230 T2059 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 16231 T2036 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 16231 T2048 C166 P55242 /update {wt=javabin&version=2} {add=[15]} 0 6
[junit4:junit4]   2> 16237 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[16 (1417272483707355136)]} 0 1
[junit4:junit4]   2> 16274 T2061 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 16275 T2037 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=33 
[junit4:junit4]   2> 16275 T2047 C166 P55242 /update {wt=javabin&version=2} {add=[16]} 0 36
[junit4:junit4]   2> 16283 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[17 (1417272483753492480)]} 0 4
[junit4:junit4]   2> 16296 T2073 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16298 T2046 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 16299 T2034 C164 P55236 /update {wt=javabin&version=2} {add=[17]} 0 14
[junit4:junit4]   2> 16302 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[100 (1417272483776561152)]} 0 0
[junit4:junit4]   2> 16314 T2056 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 16315 T2032 C164 P55236 /update {wt=javabin&version=2} {add=[100 (1417272483779706880)]} 0 10
[junit4:junit4]   2> 16321 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[101 (1417272483794386944)]} 0 2
[junit4:junit4]   2> 16331 T2058 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 16333 T2033 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16333 T2049 C166 P55242 /update {wt=javabin&version=2} {add=[101]} 0 10
[junit4:junit4]   2> 16345 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[102 (1417272483813261312)]} 0 8
[junit4:junit4]   2> 16352 T2057 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16353 T2036 C164 P55236 /update {wt=javabin&version=2} {add=[102 (1417272483823747072)]} 0 6
[junit4:junit4]   2> 16359 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[103 (1417272483833184256)]} 0 3
[junit4:junit4]   2> 16370 T2074 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16371 T2044 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16371 T2037 C164 P55236 /update {wt=javabin&version=2} {add=[103]} 0 9
[junit4:junit4]   2> 16375 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[104 (1417272483853107200)]} 0 0
[junit4:junit4]   2> 16388 T2070 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16388 T2045 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 16391 T2034 C164 P55236 /update {wt=javabin&version=2} {add=[104]} 0 11
[junit4:junit4]   2> 16393 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[105 (1417272483871981568)]} 0 0
[junit4:junit4]   2> 16421 T2072 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=11 
[junit4:junit4]   2> 16422 T2048 C166 P55242 /update {wt=javabin&version=2} {add=[105 (1417272483876175872)]} 0 25
[junit4:junit4]   2> 16427 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[106 (1417272483905536000)]} 0 2
[junit4:junit4]   2> 16440 T2071 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 16441 T2047 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 16442 T2035 C164 P55236 /update {wt=javabin&version=2} {add=[106]} 0 13
[junit4:junit4]   2> 16446 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[107 (1417272483927556096)]} 0 0
[junit4:junit4]   2> 16455 T2060 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16455 T2032 C164 P55236 /update {wt=javabin&version=2} {add=[107 (1417272483932798976)]} 0 4
[junit4:junit4]   2> 16459 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[108 (1417272483941187584)]} 0 0
[junit4:junit4]   2> 16467 T2059 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16467 T2033 C164 P55236 /update {wt=javabin&version=2} {add=[108 (1417272483945381888)]} 0 4
[junit4:junit4]   2> 16473 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[109 (1417272483953770496)]} 0 2
[junit4:junit4]   2> 16484 T2061 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 16485 T2036 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 16487 T2046 C166 P55242 /update {wt=javabin&version=2} {add=[109]} 0 10
[junit4:junit4]   2> 16490 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[110 (1417272483973693440)]} 0 0
[junit4:junit4]   2> 16499 T2056 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16500 T2037 C164 P55236 /update {wt=javabin&version=2} {add=[110 (1417272483977887744)]} 0 6
[junit4:junit4]   2> 16502 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[111 (1417272483986276352)]} 0 0
[junit4:junit4]   2> 16514 T2058 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16515 T2034 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16515 T2044 C166 P55242 /update {wt=javabin&version=2} {add=[111]} 0 9
[junit4:junit4]   2> 16527 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[112 (1417272484004102144)]} 0 8
[junit4:junit4]   2> 16537 T2073 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16537 T2045 C166 P55242 /update {wt=javabin&version=2} {add=[112 (1417272484017733632)]} 0 5
[junit4:junit4]   2> 16543 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[113 (1417272484027170816)]} 0 2
[junit4:junit4]   2> 16551 T2057 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16551 T2035 C164 P55236 /update {wt=javabin&version=2} {add=[113 (1417272484033462272)]} 0 4
[junit4:junit4]   2> 16555 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[114 (1417272484041850880)]} 0 0
[junit4:junit4]   2> 16579 T2060 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 16581 T2032 C164 P55236 /update {wt=javabin&version=2} {add=[114 (1417272484046045184)]} 0 22
[junit4:junit4]   2> 16586 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[115 (1417272484073308160)]} 0 1
[junit4:junit4]   2> 16595 T2075 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16595 T2048 C166 P55242 /update {wt=javabin&version=2} {add=[115 (1417272484078551040)]} 0 5
[junit4:junit4]   2> 16601 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[116 (1417272484087988224)]} 0 2
[junit4:junit4]   2> 16612 T2074 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16614 T2047 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 16614 T2033 C164 P55236 /update {wt=javabin&version=2} {add=[116]} 0 10
[junit4:junit4]   2> 16621 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[117 (1417272484107911168)]} 0 3
[junit4:junit4]   2> 16642 T2070 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16644 T2049 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16644 T2036 C164 P55236 /update {wt=javabin&version=2} {add=[117]} 0 20
[junit4:junit4]   2> 16647 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[118 (1417272484138319872)]} 0 0
[junit4:junit4]   2> 16659 T2059 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16660 T2037 C164 P55236 /update {wt=javabin&version=2} {add=[118 (1417272484143562752)]} 0 8
[junit4:junit4]   2> 16665 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[119 (1417272484156145664)]} 0 1
[junit4:junit4]   2> 16676 T2072 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16677 T2046 C166 P55242 /update {wt=javabin&version=2} {add=[119 (1417272484162437120)]} 0 7
[junit4:junit4]   2> 16682 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[120 (1417272484173971456)]} 0 1
[junit4:junit4]   2> 16694 T2071 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16695 T2044 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 16695 T2034 C164 P55236 /update {wt=javabin&version=2} {add=[120]} 0 9
[junit4:junit4]   2> 16702 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[121 (1417272484194942976)]} 0 1
[junit4:junit4]   2> 16714 T2073 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 16717 T2045 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 16718 T2035 C164 P55236 /update {wt=javabin&version=2} {add=[121]} 0 12
[junit4:junit4]   2> 16729 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[122 (1417272484216963072)]} 0 9
[junit4:junit4]   2> 16736 T2056 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 16738 T2033 C164 P55236 /update {wt=javabin&version=2} {add=[122 (1417272484227448832)]} 0 6
[junit4:junit4]   2> 16743 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[123 (1417272484237934592)]} 0 1
[junit4:junit4]   2> 16755 T2058 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16756 T2036 C164 P55236 /update {wt=javabin&version=2} {add=[123 (1417272484245274624)]} 0 7
[junit4:junit4]   2> 16762 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[124 (1417272484257857536)]} 0 1
[junit4:junit4]   2> 16776 T2057 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16777 T2037 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 16778 T2048 C166 P55242 /update {wt=javabin&version=2} {add=[124]} 0 12
[junit4:junit4]   2> 16784 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[125 (1417272484279877632)]} 0 2
[junit4:junit4]   2> 16807 T2060 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 16807 T2034 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=16 
[junit4:junit4]   2> 16808 T2047 C166 P55242 /update {wt=javabin&version=2} {add=[125]} 0 20
[junit4:junit4]   2> 16813 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[126 (1417272484311334912)]} 0 1
[junit4:junit4]   2> 16825 T2075 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16826 T2049 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16826 T2032 C164 P55236 /update {wt=javabin&version=2} {add=[126]} 0 10
[junit4:junit4]   2> 16832 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[127 (1417272484331257856)]} 0 1
[junit4:junit4]   2> 16856 T2070 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 16857 T2046 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=20 
[junit4:junit4]   2> 16857 T2035 C164 P55236 /update {wt=javabin&version=2} {add=[127]} 0 23
[junit4:junit4]   2> 16862 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[128 (1417272484362715136)]} 0 1
[junit4:junit4]   2> 16874 T2072 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16875 T2044 C166 P55242 /update {wt=javabin&version=2} {add=[128 (1417272484367958016)]} 0 9
[junit4:junit4]   2> 16880 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[129 (1417272484381589504)]} 0 1
[junit4:junit4]   2> 16888 T2059 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16890 T2033 C164 P55236 /update {wt=javabin&version=2} {add=[129 (1417272484386832384)]} 0 6
[junit4:junit4]   2> 16892 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[130 (1417272484395220992)]} 0 0
[junit4:junit4]   2> 16900 T2071 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16902 T2045 C166 P55242 /update {wt=javabin&version=2} {add=[130 (1417272484399415296)]} 0 6
[junit4:junit4]   2> 16905 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[131 (1417272484407803904)]} 0 1
[junit4:junit4]   2> 16917 T2073 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16917 T2047 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 16918 T2036 C164 P55236 /update {wt=javabin&version=2} {add=[131]} 0 9
[junit4:junit4]   2> 16930 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[132 (1417272484426678272)]} 0 8
[junit4:junit4]   2> 16942 T2061 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16942 T2037 C164 P55236 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 16944 T2049 C166 P55242 /update {wt=javabin&version=2} {add=[132]} 0 10
[junit4:junit4]   2> 16948 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[133 (1417272484451844096)]} 0 2
[junit4:junit4]   2> 16957 T2056 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16958 T2034 C164 P55236 /update {wt=javabin&version=2} {add=[133 (1417272484458135552)]} 0 6
[junit4:junit4]   2> 16963 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[134 (1417272484468621312)]} 0 1
[junit4:junit4]   2> 16975 T2074 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16975 T2046 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 16976 T2032 C164 P55236 /update {wt=javabin&version=2} {add=[134]} 0 10
[junit4:junit4]   2> 16980 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[135 (1417272484487495680)]} 0 0
[junit4:junit4]   2> 16990 T2058 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16992 T2035 C164 P55236 /update {wt=javabin&version=2} {add=[135 (1417272484490641408)]} 0 9
[junit4:junit4]   2> 16996 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[136 (1417272484503224320)]} 0 1
[junit4:junit4]   2> 17003 T2075 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17003 T2044 C166 P55242 /update {wt=javabin&version=2} {add=[136 (1417272484507418624)]} 0 4
[junit4:junit4]   2> 17008 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[137 (1417272484516855808)]} 0 0
[junit4:junit4]   2> 17017 T2070 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 17018 T2048 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 17018 T2033 C164 P55236 /update {wt=javabin&version=2} {add=[137]} 0 7
[junit4:junit4]   2> 17021 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[138 (1417272484530487296)]} 0 0
[junit4:junit4]   2> 17054 T2057 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 17055 T2036 C164 P55236 /update {wt=javabin&version=2} {add=[138 (1417272484534681600)]} 0 30
[junit4:junit4]   2> 17059 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[139 (1417272484570333184)]} 0 0
[junit4:junit4]   2> 17067 T2072 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17067 T2045 C166 P55242 /update {wt=javabin&version=2} {add=[139 (1417272484573478912)]} 0 5
[junit4:junit4]   2> 17072 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[140 (1417272484582916096)]} 0 1
[junit4:junit4]   2> 17084 T2071 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17085 T2047 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 17086 T2037 C164 P55236 /update {wt=javabin&version=2} {add=[140]} 0 11
[junit4:junit4]   2> 17090 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[141 (1417272484602839040)]} 0 0
[junit4:junit4]   2> 17101 T2073 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 17103 T2049 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 17104 T2034 C164 P55236 /update {wt=javabin&version=2} {add=[141]} 0 9
[junit4:junit4]   2> 17116 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[142 (1417272484621713408)]} 0 8
[junit4:junit4]   2> 17139 T2074 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 17139 T2046 C166 P55242 /update {wt=javabin&version=2} {add=[142 (1417272484635344896)]} 0 18
[junit4:junit4]   2> 17162 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[143 (1417272484675190784)]} 0 6
[junit4:junit4]   2> 17176 T2075 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17177 T2044 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 17177 T2032 C164 P55236 /update {wt=javabin&version=2} {add=[143]} 0 12
[junit4:junit4]   2> 17182 T2018 C167 P55230 /update {wt=javabin&version=2} {add=[144 (1417272484698259456)]} 0 1
[junit4:junit4]   2> 17191 T2070 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17191 T2048 C166 P55242 /update {wt=javabin&version=2} {add=[144 (1417272484703502336)]} 0 5
[junit4:junit4]   2> 17195 T2021 C167 P55230 /update {wt=javabin&version=2} {add=[145 (1417272484711890944)]} 0 1
[junit4:junit4]   2> 17206 T2072 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17207 T2045 C166 P55242 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 17207 T2033 C164 P55236 /update {wt=javabin&version=2} {add=[145]} 0 8
[junit4:junit4]   2> 17211 T2022 C167 P55230 /update {wt=javabin&version=2} {add=[146 (1417272484729716736)]} 0 0
[junit4:junit4]   2> 17221 T2060 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17222 T2036 C164 P55236 /update {wt=javabin&version=2} {add=[146 (1417272484733911040)]} 0 7
[junit4:junit4]   2> 17225 T2023 C167 P55230 /update {wt=javabin&version=2} {add=[147 (1417272484744396800)]} 0 0
[junit4:junit4]   2> 17234 T2071 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17235 T2047 C166 P55242 /update {wt=javabin&version=2} {add=[147 (1417272484748591104)]} 0 6
[junit4:junit4]   2> 17239 T2020 C167 P55230 /update {wt=javabin&version=2} {add=[148 (1417272484759076864)]} 0 0
[junit4:junit4]   2> 17247 T2061 C163 P55248 REQ /update {distrib.from=http://127.0.0.1:55236/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17247 T2037 C164 P55236 /update {wt=javabin&version=2} {add=[148 (1417272484762222592)]} 0 5
[junit4:junit4]   2> 17251 T2019 C167 P55230 /update {wt=javabin&version=2} {add=[149 (1417272484771659776)]} 0 0
[junit4:junit4]   2> 17259 T2074 C165 P55256 REQ /update {distrib.from=http://127.0.0.1:55242/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17260 T2049 C166 P55242 /update {wt=javabin&version=2} {add=[149 (1417272484774805504)]} 0 6
[junit4:junit4]   2> 17263 T2018 C167 P55230 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 17420 T2018 C167 P55230 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.BasicDistributedZkTest-1351616350828\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a36a26; 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.BasicDistributedZkTest-1351616350828\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a36a26; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0.tis, _5.fdt, _5.prx, _1.frq, _4.prx, _4.fnm, _2.tii, _3.fdt, _1.fnm, _4.fdx, _4.frq, _5.tis, _5.nrm, _0.prx, _3.nrm, _0.fnm, _2.prx, _2.fdt, _2.frq, _3.prx, _5.tii, _2.fdx, _5.si, _1.prx, _1.fdx, _2.tis, _0.tii, _1.fdt, _0.frq, _3.si, _6.fdt, _6.nrm, _1.nrm, _2.nrm, _2.si, _3.frq, _3.tii, _6.frq, _6.fdx, _3.fnm, _6.tis, _1.tii, _0.si, _4.tis, _0.nrm, _4.tii, _5.fnm, _1.tis, _6.tii, _4.nrm, _5.frq, _4.fdt, _4.si, _2.fnm, _3.fdx, _6.prx, _1.si, _5.fdx, segments_2, _6.si, _6.fnm, _0.fdx, _0.fdt, _3.tis]
[junit4:junit4]   2> 17421 T2018 C167 P55230 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 17427 T2018 C167 P55230 oass.SolrIndexSearcher.<init> Opening Searcher@140db57 main
[junit4:junit4]   2> 17428 T2018 C167 P55230 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17429 T2028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@140db57 main{StandardDirectoryReader(segments_2:15:nrt _0(4.1):C10 _1(4.1):C10 _2(4.1):C10 _3(4.1):C10 _4(4.1):C10 _5(4.1):C10 _6(4.1):C7)}
[junit4:junit4]   2> 17429 T2018 C167 P55230 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 166
[junit4:junit4]   2> 17433 T2046 C166 P55242 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 17532 T2046 C166 P55242 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.BasicDistributedZkTest-1351616350828\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c2cfb; 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.BasicDistributedZkTest-1351616350828\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c2cfb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_2.si, _0.tis, _3.tii, _3.frq, _1.frq, _3.fdt, _3.fnm, _2.tii, _1.fnm, _1.tii, _0.si, _0.prx, _3.nrm, _0.nrm, _0.fnm, _1.tis, _2.prx, _2.frq, _2.fdt, _3.prx, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1.prx, _2.tis, _0.tii, _1.fdt, _1.si, _0.frq, segments_2, _3.si, _0.fdx, _2.nrm, _0.fdt, _1.nrm, _3.tis]
[junit4:junit4]   2> 17534 T2046 C166 P55242 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 17540 T2046 C166 P55242 oass.SolrIndexSearcher.<init> Opening Searcher@c56553 main
[junit4:junit4]   2> 17541 T2046 C166 P55242 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17543 T2052 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c56553 main{StandardDirectoryReader(segments_2:9:nrt _0(4.1):C10 _1(4.1):C10 _2(4.1):C10 _3(4.1):C5)}
[junit4:junit4]   2> 17544 T2046 C166 P55242 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:55236/solr/collection1/, StdNode: http://127.0.0.1:55248/solr/collection1/, StdNode: http://127.0.0.1:55256/solr/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2> 17547 T2056 C163 P55248 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 17547 T2034 C164 P55236 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 17548 T2075 C165 P55256 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 17710 T2034 C164 P55236 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.BasicDistributedZkTest-1351616350828\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c58a9d; 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.BasicDistributedZkTest-1351616350828\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c58a9d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_2.si, _0.tis, _3.tii, _3.frq, _1.frq, _3.fdt, _3.fnm, _2.tii, _1.fnm, _1.tii, _0.si, _0.prx, _3.nrm, _0.nrm, _0.fnm, _1.tis, _2.prx, _2.frq, _2.fdt, _3.prx, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1.prx, _2.tis, _0.tii, _1.fdt, _1.si, _0.frq, segments_2, _3.si, _0.fdx, _2.nrm, _0.fdt, _1.nrm, _3.tis]
[junit4:junit4]   2> 17711 T2034 C164 P55236 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 17718 T2034 C164 P55236 oass.SolrIndexSearcher.<init> Opening Searcher@1e66d5c main
[junit4:junit4]   2> 17720 T2034 C164 P55236 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17722 T2040 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e66d5c main{StandardDirectoryReader(segments_2:9:nrt _0(4.1):C10 _1(4.1):C10 _2(4.1):C10 _3(4.1):C2)}
[junit4:junit4]   2> 17722 T2034 C164 P55236 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 175
[junit4:junit4]   2> 17735 T2075 C165 P55256 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.BasicDistributedZkTest-1351616350828\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43084b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_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.BasicDistributedZkTest-1351616350828\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43084b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_2.si, _0.tis, _3.tii, _3.frq, _1.frq, _3.fdt, _3.fnm, _2.tii, _1.fnm, _1.tii, _0.si, _0.prx, _3.nrm, _0.nrm, _0.fnm, _1.tis, _2.prx, _2.frq, _2.fdt, _3.prx, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1.prx, _2.tis, _0.tii, _1.fdt, _1.si, _0.frq, _3.si, _0.fdx, _2.nrm, _0.fdt, segments_3, _1.nrm, _3.tis]
[junit4:junit4]   2> 17736 T2075 C165 P55256 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 17742 T2075 C165 P55256 oass.SolrIndexSearcher.<init> Opening Searcher@1946d2b main
[junit4:junit4]   2> 17745 T2075 C165 P55256 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17746 T2056 C163 P55248 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.BasicDistributedZkTest-1351616350828\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a432d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_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.BasicDistributedZkTest-1351616350828\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a432d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_2.si, _0.tis, _3.tii, _3.frq, _1.frq, _3.fdt, _3.fnm, _2.tii, _1.fnm, _1.tii, _0.si, _0.prx, _3.nrm, _0.nrm, _0.fnm, _1.tis, _2.prx, _2.frq, _2.fdt, _3.prx, _2.fdx, _2.fnm, _3.fdx, _1.fdx, _1.prx, _2.tis, _0.tii, _1.fdt, _1.si, _0.frq, _3.si, _0.fdx, _2.nrm, _0.fdt, segments_3, _1.nrm, _3.tis]
[junit4:junit4]   2> 17746 T2078 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1946d2b main{StandardDirectoryReader(segments_3:10:nrt _0(4.1):C10 _1(4.1):C10 _2(4.1):C10 _3(4.1):C5)}
[junit4:junit4]   2> 17746 T2056 C163 P55248 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 17746 T2075 C165 P55256 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 198
[junit4:junit4]   2> 17751 T2056 C163 P55248 oass.SolrIndexSearcher.<init> Opening Searcher@bcf182 main
[junit4:junit4]   2> 17751 T2056 C163 P55248 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17752 T2064 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bcf182 main{StandardDirectoryReader(segments_3:10:nrt _0(4.1):C10 _1(4.1):C10 _2(4.1):C10 _3(4.1):C2)}
[junit4:junit4]   2> 17753 T2056 C163 P55248 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 206
[junit4:junit4]   2> 17753 T2046 C166 P55242 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 320
[junit4:junit4]   2> 17759 T2022 C167 P55230 REQ /select {distrib=false&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17768 T2048 C166 P55242 REQ /select {sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1351616368588&shard.url=127.0.0.1:55242/solr/collection1/|127.0.0.1:55256/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17768 T2057 C163 P55248 REQ /select {sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1351616368588&shard.url=127.0.0.1:55236/solr/collection1/|127.0.0.1:55248/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17777 T2045 C166 P55242 REQ /select {shard.url=127.0.0.1:55242/solr/collection1/|127.0.0.1:55256/solr/collection1/&NOW=1351616368588&q=*:*&ids=127,149,17,105,119&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 17777 T2059 C163 P55248 REQ /select {shard.url=127.0.0.1:55236/solr/collection1/|127.0.0.1:55248/solr/collection1/&NOW=1351616368588&q=*:*&ids=109,4,107,118,100&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 17778 T2032 C164 P55236 REQ /select {collection=collection1&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17781 T2023 C167 P55230 REQ /select {distrib=false&sort=n_ti1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 17788 T2072 C165 P55256 REQ /select {sort=n_ti1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1351616368608&shard.url=127.0.0.1:55242/solr/collection1/|127.0.0.1:55256/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17789 T2033 C164 P55236 REQ /select {sort=n_ti1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1351616368608&shard.url=127.0.0.1:55236/solr/collection1/|127.0.0.1:55248/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17796 T2036 C164 P55236 REQ /select {shard.url=127.0.0.1:55236/solr/collection1/|127.0.0.1:55248/solr/collection1/&NOW=1351616368608&q=*:*&ids=125,122,8,102&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17798 T2073 C165 P55256 REQ /select {shard.url=127.0.0.1:55242/solr/collection1/|127.0.0.1:55256/solr/collection1/&NOW=1351616368608&q=*:*&ids=145,147,9,104,120,103&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 17799 T2060 C163 P55248 REQ /select {collection=collection1&sort=n_ti1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=16 
[junit4:junit4]   2> 17805 T2020 C167 P55230 REQ /select {distrib=false&sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=3 
[junit4:junit4]   2> 17807 T2005 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 17812 T2005 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55223/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@95aa91
[junit4:junit4]   2> 17814 T2093 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55223
[junit4:junit4]   2> 17814 T2005 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 17815 T2093 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55223, initiating session
[junit4:junit4]   2> 17815 T2007 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55288
[junit4:junit4]   2> 17815 T2007 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55288
[junit4:junit4]   2> 17818 T2009 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ab29cca760009 with negotiated timeout 10000 for client /127.0.0.1:55288
[junit4:junit4]   2> 17818 T2093 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55223, sessionid = 0x13ab29cca760009, negotiated timeout = 10000
[junit4:junit4]   2> 17819 T2094 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@95aa91 name:ZooKeeperConnection Watcher:127.0.0.1:55223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 17819 T2005 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 17820 T2005 oascc.Z

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

junit4]   1>                 "leader":"true"}}}}}
[junit4:junit4]   1>  /zookeeper (1)
[junit4:junit4]   1>  DATA:
[junit4:junit4]   1>      
[junit4:junit4]   1> 
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=F6153C53DBD53F99 -Dtests.slow=true -Dtests.locale=sv -Dtests.timezone=Pacific/Majuro -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] FAILURE  136s | BasicDistributedZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: Leader never changed
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F6153C53DBD53F99:77F3B24BAC8A5FA5]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZkTest.testCoreUnloadAndLeaders(BasicDistributedZkTest.java:446)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:335)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[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$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[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 com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[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:722)
[junit4:junit4]   2> 136275 T2005 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene3x, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=sv, timezone=Pacific/Majuro
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_07 (32-bit)/cpus=2,threads=1,free=50029000,total=177102848
[junit4:junit4]   2> NOTE: All tests run in this JVM: [DistributedTermsComponentTest, TestHashPartitioner, TestArbitraryIndexDir, TestDocSet, ZkCLITest, TestFastWriter, TestFastOutputStream, TestUpdate, SimpleFacetsTest, DirectUpdateHandlerOptimizeTest, ShowFileRequestHandlerTest, ZkControllerTest, TestStressReorder, TestQueryUtils, TestRandomFaceting, TermVectorComponentTest, FileUtilsTest, TestAnalyzedSuggestions, UpdateRequestProcessorFactoryTest, TestDistributedSearch, TestPHPSerializedResponseWriter, PathHierarchyTokenizerFactoryTest, BasicFunctionalityTest, DistributedSpellCheckComponentTest, TestSolrCoreProperties, TestFastLRUCache, FullSolrCloudDistribCmdsTest, AnalysisAfterCoreReloadTest, ClusterStateTest, PreAnalyzedFieldTest, SOLR749Test, ResourceLoaderTest, SignatureUpdateProcessorFactoryTest, TestStressVersions, SortByFunctionTest, ConvertedLegacyTest, CurrencyFieldTest, RAMDirectoryFactoryTest, BasicDistributedZk2Test, XmlUpdateRequestHandlerTest, IndexReaderFactoryTest, TestRTGBase, TestGroupingSearch, TestSurroundQueryParser, TestCodecSupport, UniqFieldsUpdateProcessorFactoryTest, ReturnFieldsTest, TestIBSimilarityFactory, TestDocumentBuilder, TestCSVLoader, TestOmitPositions, XsltUpdateRequestHandlerTest, ScriptEngineTest, SimplePostToolTest, SampleTest, FastVectorHighlighterTest, TestQuerySenderNoQuery, TestLFUCache, IndexSchemaTest, PluginInfoTest, SolrInfoMBeanTest, TestSolrDeletionPolicy2, RequiredFieldsTest, TestSolrXMLSerializer, PolyFieldTest, ClusterStateUpdateTest, TestPerFieldSimilarity, RequestHandlersTest, StandardRequestHandlerTest, UpdateParamsTest, DirectUpdateHandlerTest, MBeansHandlerTest, SuggesterTest, TestXIncludeConfig, TestQueryTypes, AutoCommitTest, TestReversedWildcardFilterFactory, TestRealTimeGet, TestExtendedDismaxParser, DisMaxRequestHandlerTest, QueryElevationComponentTest, TestConfig, LukeRequestHandlerTest, DirectSolrConnectionTest, SolrRequestParserTest, CoreAdminHandlerTest, StatsComponentTest, CacheHeaderTest, TestRecovery, DistributedQueryElevationComponentTest, SoftAutoCommitTest, OverseerTest, ZkNodePropsTest, TestSearchPerf, SuggesterWFSTTest, SuggesterFSTTest, TestLRUCache, TestMultiCoreConfBootstrap, TimeZoneUtilsTest, TestCharFilters, TestFoldingMultitermQuery, TestQuerySenderListener, HighlighterTest, TestReload, TestSystemIdResolver, LeaderElectionIntegrationTest, WordBreakSolrSpellCheckerTest, TestValueSourceCache, SpellCheckCollatorTest, SpellingQueryConverterTest, BasicDistributedZkTest]
[junit4:junit4] Completed in 137.40s, 1 test, 1 failure <<< FAILURES!

[...truncated 360 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:335: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:178: 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:1159: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:827: There were test failures: 233 suites, 965 tests, 1 failure, 12 ignored (7 assumptions)

Total time: 36 minutes 50 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_07 -server -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message