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 (64bit/jdk1.6.0_37) - Build # 1425 - Failure!
Date Sun, 04 Nov 2012 04:39:53 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/1425/
Java: 64bit/jdk1.6.0_37 -XX:+UseSerialGC

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

Error Message:
There are still nodes recoverying - waited for 180 seconds

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 180 seconds
	at __randomizedtesting.SeedInfo.seed([C62887E5C8588B7D:47CE09FDBF07EB41]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:165)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:115)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:110)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:553)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:65)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$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:662)




Build Log:
[...truncated 7765 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   2> 4 T699 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicDistributedZk2Test-1352002784965
[junit4:junit4]   2> 6 T699 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 7 T700 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 8 T700 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 9 T700 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 89 T699 oasc.ZkTestServer.run start zk server on port:61832
[junit4:junit4]   2> 89 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7f676908
[junit4:junit4]   2> 90 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 90 T705 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 93 T705 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 93 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61835
[junit4:junit4]   2> 93 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61835
[junit4:junit4]   2> 94 T703 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 101 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0000 with negotiated timeout 10000 for client /127.0.0.1:61835
[junit4:junit4]   2> 101 T705 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0000, negotiated timeout = 10000
[junit4:junit4]   2> 101 T706 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7f676908 name:ZooKeeperConnection Watcher:127.0.0.1:61832 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 101 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 102 T699 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 109 T704 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ac9a54ecb0000
[junit4:junit4]   2> 116 T699 oaz.ZooKeeper.close Session: 0x13ac9a54ecb0000 closed
[junit4:junit4]   2> 116 T701 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:61835 which had sessionid 0x13ac9a54ecb0000
[junit4:junit4]   2> 117 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c6415e2
[junit4:junit4]   2> 116 T706 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 118 T707 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 118 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 119 T707 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 119 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61838
[junit4:junit4]   2> 119 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61838
[junit4:junit4]   2> 131 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0001 with negotiated timeout 10000 for client /127.0.0.1:61838
[junit4:junit4]   2> 131 T707 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0001, negotiated timeout = 10000
[junit4:junit4]   2> 132 T708 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c6415e2 name:ZooKeeperConnection Watcher:127.0.0.1:61832/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 132 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 132 T699 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 148 T699 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 157 T699 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 172 T699 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 182 T699 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> 182 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 204 T699 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> 205 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 212 T699 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> 212 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 221 T699 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> 221 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 227 T699 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> 228 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 235 T699 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> 236 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 242 T699 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> 243 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 250 T699 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> 251 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 259 T699 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> 259 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 266 T699 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> 267 T699 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 281 T704 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ac9a54ecb0001
[junit4:junit4]   2> 287 T699 oaz.ZooKeeper.close Session: 0x13ac9a54ecb0001 closed
[junit4:junit4]   2> 287 T701 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:61838 which had sessionid 0x13ac9a54ecb0001
[junit4:junit4]   2> 288 T708 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 289 T699 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 298 T699 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:61839
[junit4:junit4]   2> 299 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 299 T699 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> 299 T699 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> 318 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 318 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 319 T699 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> 319 T699 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> 319 T699 oasc.CoreContainer.<init> New CoreContainer 975826879
[junit4:junit4]   2> 320 T699 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> 320 T699 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> 333 T699 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 347 T699 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:61832/solr
[junit4:junit4]   2> 347 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@281c7736
[junit4:junit4]   2> 349 T718 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 349 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 350 T718 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 350 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61844
[junit4:junit4]   2> 350 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61844
[junit4:junit4]   2> 363 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0002 with negotiated timeout 5000 for client /127.0.0.1:61844
[junit4:junit4]   2> 363 T718 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0002, negotiated timeout = 5000
[junit4:junit4]   2> 363 T719 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@281c7736 name:ZooKeeperConnection Watcher:127.0.0.1:61832/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 363 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 370 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 395 T699 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 418 T699 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61839_solr
[junit4:junit4]   2> 419 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:61839_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:61839_solr
[junit4:junit4]   2> 441 T699 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61839_solr
[junit4:junit4]   2> 459 T699 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 460 T699 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 461 T699 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 461 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 461 T699 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 462 T699 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 462 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 463 T699 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 464 T699 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 464 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 476 T699 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 528 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 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> 542 T699 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 566 T699 oasc.Overseer.start Overseer (id=88604854515859458-127.0.0.1:61839_solr-n_0000000000) starting
[junit4:junit4]   2> 568 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 590 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 614 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 638 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 661 T721 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 663 T699 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 701 T699 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 706 T720 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 715 T699 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> 716 T699 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 716 T699 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 717 T699 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 720 T699 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> 724 T699 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> 726 T699 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> 767 T699 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 816 T699 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 817 T699 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 821 T699 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1483 T699 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1490 T699 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1493 T699 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1509 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1514 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1521 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1526 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1530 T699 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/control/data\
[junit4:junit4]   2> 1530 T699 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb273cc
[junit4:junit4]   2> 1532 T699 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/control/data\index/
[junit4:junit4]   2> 1533 T699 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1533 T699 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\control\data\index forceNew:false
[junit4:junit4]   2> 1584 T699 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ec684b3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1584 T699 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1588 T699 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1590 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1590 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1590 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1590 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1591 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1591 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1592 T699 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1592 T699 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1593 T699 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1593 T699 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1593 T699 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1594 T699 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1594 T699 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1594 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1595 T699 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1595 T699 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1596 T699 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1596 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1596 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1597 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1597 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1597 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1598 T699 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1598 T699 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1599 T699 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1605 T699 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1610 T699 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1611 T699 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> 1615 T699 oass.SolrIndexSearcher.<init> Opening Searcher@683eab6 main
[junit4:junit4]   2> 1617 T699 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1617 T699 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1617 T699 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1664 T699 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1876 T722 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@683eab6 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1878 T699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1879 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1917 T699 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1917 T699 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:61839/solr shardId:control_shard
[junit4:junit4]   2> 1919 T699 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1973 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:delete cxid:0x65 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> 1996 T699 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1997 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2054 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2054 T720 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:61839_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61839/solr"}
[junit4:junit4]   2> 2054 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 2217 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2429 T699 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2430 T699 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2430 T699 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61839/solr/collection1/
[junit4:junit4]   2> 2431 T699 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2431 T699 oasc.SyncStrategy.syncToMe http://127.0.0.1:61839/solr/collection1/ has no replicas
[junit4:junit4]   2> 2431 T699 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61839/solr/collection1/
[junit4:junit4]   2> 2432 T699 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 2453 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0002 type:create cxid:0x8b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2652 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2723 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2772 T699 oasc.ZkController.register We are http://127.0.0.1:61839/solr/collection1/ and leader is http://127.0.0.1:61839/solr/collection1/
[junit4:junit4]   2> 2772 T699 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61839/solr
[junit4:junit4]   2> 2772 T699 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2772 T699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2799 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2800 T699 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2800 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2800 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3000 T699 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3002 T699 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:61845
[junit4:junit4]   2> 3003 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3004 T699 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-1352002787766
[junit4:junit4]   2> 3004 T699 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-1352002787766\'
[junit4:junit4]   2> 3032 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3032 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3033 T699 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-1352002787766
[junit4:junit4]   2> 3033 T699 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-1352002787766\solr.xml
[junit4:junit4]   2> 3034 T699 oasc.CoreContainer.<init> New CoreContainer 426204567
[junit4:junit4]   2> 3034 T699 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-1352002787766\'
[junit4:junit4]   2> 3034 T699 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1352002787766\'
[junit4:junit4]   2> 3051 T699 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3063 T699 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:61832/solr
[junit4:junit4]   2> 3064 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@514c15d
[junit4:junit4]   2> 3065 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3065 T732 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 3066 T732 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 3066 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61850
[junit4:junit4]   2> 3067 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61850
[junit4:junit4]   2> 3085 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0003 with negotiated timeout 5000 for client /127.0.0.1:61850
[junit4:junit4]   2> 3085 T732 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0003, negotiated timeout = 5000
[junit4:junit4]   2> 3085 T733 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@514c15d name:ZooKeeperConnection Watcher:127.0.0.1:61832/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3086 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3086 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3107 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3113 T699 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61845_solr
[junit4:junit4]   2> 3113 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:61845_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:61845_solr
[junit4:junit4]   2> 3134 T699 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61845_solr
[junit4:junit4]   2> 3137 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3162 T720 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:61839_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61839/solr"}
[junit4:junit4]   2> 3163 T699 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3163 T699 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3163 T699 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3163 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3164 T699 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3164 T699 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3164 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3165 T699 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3165 T699 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3165 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3230 T699 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3263 T699 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-1352002787766\collection1
[junit4:junit4]   2> 3263 T699 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3279 T699 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3280 T699 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3280 T719 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 3281 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3282 T699 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty1-1352002787766\collection1\'
[junit4:junit4]   2> 3284 T699 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty1-1352002787766/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3285 T699 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty1-1352002787766/collection1/lib/README' to classloader
[junit4:junit4]   2> 3331 T699 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 3390 T699 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3392 T699 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3399 T699 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3938 T699 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3945 T699 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3948 T699 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3969 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3973 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3979 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3980 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3982 T699 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-1352002787766\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/jetty1\
[junit4:junit4]   2> 3983 T699 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb273cc
[junit4:junit4]   2> 3984 T699 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/jetty1\index/
[junit4:junit4]   2> 3984 T699 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3985 T699 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty1\index forceNew:false
[junit4:junit4]   2> 4029 T699 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43e87b75; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4029 T699 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4031 T699 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4032 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4033 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4033 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4033 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4034 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4034 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4034 T699 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4035 T699 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4035 T699 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4036 T699 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4036 T699 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4037 T699 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4037 T699 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4038 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4038 T699 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4039 T699 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4040 T699 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4040 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4040 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4040 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4041 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4041 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4042 T699 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4043 T699 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4043 T699 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4050 T699 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4054 T699 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4058 T699 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> 4063 T699 oass.SolrIndexSearcher.<init> Opening Searcher@6e987694 main
[junit4:junit4]   2> 4064 T699 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4064 T699 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4065 T699 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4108 T699 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4332 T734 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e987694 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4336 T699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4591 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4591 T720 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:61845_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61845/solr"}
[junit4:junit4]   2> 4591 T720 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 4638 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4638 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4794 T699 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4794 T699 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:61845/solr shardId:shard1
[junit4:junit4]   2> 4796 T699 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 4901 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0003 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 4926 T699 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4926 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0003 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4978 T699 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4978 T699 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4978 T699 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61845/solr/collection1/
[junit4:junit4]   2> 4979 T699 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4979 T699 oasc.SyncStrategy.syncToMe http://127.0.0.1:61845/solr/collection1/ has no replicas
[junit4:junit4]   2> 4979 T699 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61845/solr/collection1/
[junit4:junit4]   2> 4980 T699 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 5004 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5093 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5211 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5212 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5232 T699 oasc.ZkController.register We are http://127.0.0.1:61845/solr/collection1/ and leader is http://127.0.0.1:61845/solr/collection1/
[junit4:junit4]   2> 5232 T699 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61845/solr
[junit4:junit4]   2> 5233 T699 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5234 T699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5282 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5283 T699 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 5283 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5284 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5517 T699 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5519 T699 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:61851
[junit4:junit4]   2> 5520 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5520 T699 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-1352002790253
[junit4:junit4]   2> 5521 T699 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-1352002790253\'
[junit4:junit4]   2> 5559 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5560 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5560 T699 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-1352002790253
[junit4:junit4]   2> 5561 T699 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-1352002790253\solr.xml
[junit4:junit4]   2> 5561 T699 oasc.CoreContainer.<init> New CoreContainer 199570377
[junit4:junit4]   2> 5561 T699 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-1352002790253\'
[junit4:junit4]   2> 5561 T699 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1352002790253\'
[junit4:junit4]   2> 5582 T699 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5593 T699 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:61832/solr
[junit4:junit4]   2> 5594 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1bfe9f8
[junit4:junit4]   2> 5594 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5595 T744 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 5597 T744 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 5598 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61856
[junit4:junit4]   2> 5600 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61856
[junit4:junit4]   2> 5626 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0004 with negotiated timeout 5000 for client /127.0.0.1:61856
[junit4:junit4]   2> 5627 T744 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0004, negotiated timeout = 5000
[junit4:junit4]   2> 5627 T745 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1bfe9f8 name:ZooKeeperConnection Watcher:127.0.0.1:61832/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5627 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5628 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5644 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5669 T699 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61851_solr
[junit4:junit4]   2> 5670 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:61851_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:61851_solr
[junit4:junit4]   2> 5696 T699 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61851_solr
[junit4:junit4]   2> 5706 T719 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 5706 T733 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 5706 T699 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5706 T699 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5706 T699 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5706 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5706 T699 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5707 T699 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5707 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5707 T699 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5707 T699 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5707 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5739 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5739 T699 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5741 T720 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:61845_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61845/solr"}
[junit4:junit4]   2> 5769 T699 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-1352002790253\collection1
[junit4:junit4]   2> 5770 T699 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5790 T699 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5791 T699 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5808 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5808 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5808 T745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5816 T699 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty2-1352002790253\collection1\'
[junit4:junit4]   2> 5817 T699 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty2-1352002790253/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5818 T699 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty2-1352002790253/collection1/lib/README' to classloader
[junit4:junit4]   2> 5875 T699 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 5939 T699 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5940 T699 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5945 T699 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6535 T699 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6544 T699 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6547 T699 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6571 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6576 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6581 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6583 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6585 T699 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-1352002790253\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/jetty2\
[junit4:junit4]   2> 6585 T699 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb273cc
[junit4:junit4]   2> 6586 T699 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/jetty2\index/
[junit4:junit4]   2> 6587 T699 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6587 T699 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty2\index forceNew:false
[junit4:junit4]   2> 6620 T699 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5738e61c; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6620 T699 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6622 T699 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6623 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6624 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6624 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6624 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6625 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6625 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6625 T699 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6625 T699 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6625 T699 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6625 T699 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6626 T699 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6626 T699 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6627 T699 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6627 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6627 T699 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6628 T699 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6628 T699 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6628 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6629 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6629 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6629 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6630 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6630 T699 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6630 T699 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6630 T699 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6636 T699 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6639 T699 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6641 T699 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> 6645 T699 oass.SolrIndexSearcher.<init> Opening Searcher@d551e1 main
[junit4:junit4]   2> 6647 T699 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6647 T699 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6647 T699 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6694 T699 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6907 T746 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d551e1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6914 T699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7149 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7150 T720 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:61851_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61851/solr"}
[junit4:junit4]   2> 7206 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7208 T745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7207 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7358 T699 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7358 T699 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:61851/solr shardId:shard2
[junit4:junit4]   2> 7360 T699 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 7443 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0004 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> 7449 T699 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7450 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0004 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7502 T699 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7503 T699 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7503 T699 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61851/solr/collection1/
[junit4:junit4]   2> 7503 T699 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7504 T699 oasc.SyncStrategy.syncToMe http://127.0.0.1:61851/solr/collection1/ has no replicas
[junit4:junit4]   2> 7504 T699 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61851/solr/collection1/
[junit4:junit4]   2> 7504 T699 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 7520 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0004 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7670 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7778 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7779 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7779 T745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7783 T699 oasc.ZkController.register We are http://127.0.0.1:61851/solr/collection1/ and leader is http://127.0.0.1:61851/solr/collection1/
[junit4:junit4]   2> 7783 T699 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61851/solr
[junit4:junit4]   2> 7783 T699 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7783 T699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7822 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7849 T699 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 7849 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7850 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8066 T699 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 8068 T699 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:61857
[junit4:junit4]   2> 8070 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8070 T699 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-1352002792820
[junit4:junit4]   2> 8071 T699 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-1352002792820\'
[junit4:junit4]   2> 8106 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8106 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8106 T699 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-1352002792820
[junit4:junit4]   2> 8106 T699 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-1352002792820\solr.xml
[junit4:junit4]   2> 8107 T699 oasc.CoreContainer.<init> New CoreContainer 1507883840
[junit4:junit4]   2> 8107 T699 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-1352002792820\'
[junit4:junit4]   2> 8107 T699 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1352002792820\'
[junit4:junit4]   2> 8137 T699 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8152 T699 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:61832/solr
[junit4:junit4]   2> 8153 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@633b80c3
[junit4:junit4]   2> 8154 T756 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 8154 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8156 T756 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 8156 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61862
[junit4:junit4]   2> 8156 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61862
[junit4:junit4]   2> 8179 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0005 with negotiated timeout 5000 for client /127.0.0.1:61862
[junit4:junit4]   2> 8179 T756 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0005, negotiated timeout = 5000
[junit4:junit4]   2> 8179 T757 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@633b80c3 name:ZooKeeperConnection Watcher:127.0.0.1:61832/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8179 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8181 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8195 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8221 T699 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61857_solr
[junit4:junit4]   2> 8222 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:61857_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:61857_solr
[junit4:junit4]   2> 8246 T699 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61857_solr
[junit4:junit4]   2> 8273 T733 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 8273 T719 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 8274 T745 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 8275 T699 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8275 T699 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8275 T699 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8276 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8276 T699 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8276 T699 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8276 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8277 T699 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8277 T699 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8277 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8285 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8286 T720 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:61851_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61851/solr"}
[junit4:junit4]   2> 8326 T699 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8341 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8341 T757 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> 8341 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8341 T745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8352 T699 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-1352002792820\collection1
[junit4:junit4]   2> 8352 T699 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8375 T699 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8376 T699 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8378 T699 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty3-1352002792820\collection1\'
[junit4:junit4]   2> 8379 T699 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty3-1352002792820/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8380 T699 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty3-1352002792820/collection1/lib/README' to classloader
[junit4:junit4]   2> 8418 T699 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 8477 T699 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8478 T699 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8483 T699 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8936 T699 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 8943 T699 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8947 T699 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8964 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8967 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8970 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8972 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8974 T699 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-1352002792820\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/jetty3\
[junit4:junit4]   2> 8974 T699 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb273cc
[junit4:junit4]   2> 8975 T699 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/jetty3\index/
[junit4:junit4]   2> 8976 T699 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty3\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8976 T699 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty3\index forceNew:false
[junit4:junit4]   2> 9020 T699 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5eb1ea22; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9020 T699 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 9022 T699 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 9023 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9023 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 9024 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9024 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 9024 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9025 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9025 T699 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9025 T699 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9025 T699 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9025 T699 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 9025 T699 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 9026 T699 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9026 T699 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 9026 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 9026 T699 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 9027 T699 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9027 T699 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9027 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9028 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9029 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9029 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9029 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9030 T699 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9030 T699 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 9030 T699 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 9036 T699 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9039 T699 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 9040 T699 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> 9042 T699 oass.SolrIndexSearcher.<init> Opening Searcher@ce5988b main
[junit4:junit4]   2> 9044 T699 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9044 T699 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9044 T699 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 9092 T699 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 9321 T758 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ce5988b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9327 T699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9684 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9685 T720 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:61857_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61857/solr"}
[junit4:junit4]   2> 9743 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 9743 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 9743 T745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 79052 T699 oasc.SolrException.log SEVERE null:org.apache.solr.common.SolrException: Could not get shard_id for core: collection1
[junit4:junit4]   2> 		at org.apache.solr.cloud.ZkController.doGetShardIdProcess(ZkController.java:996)
[junit4:junit4]   2> 		at org.apache.solr.cloud.ZkController.preRegister(ZkController.java:1054)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.register(CoreContainer.java:661)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.load(CoreContainer.java:531)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.load(CoreContainer.java:351)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer$Initializer.initialize(CoreContainer.java:303)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:107)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.FilterHolder.doStart(FilterHolder.java:114)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:59)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:754)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1227)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1263)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:965)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:923)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:333)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:173)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:169)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:60)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:225)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:205)
[junit4:junit4]   2> 		at org.apache.solr.BaseDistributedSearchTestCase.createJetty(BaseDistributedSearchTestCase.java:262)
[junit4:junit4]   2> 		at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createJettys(AbstractFullDistribZkTestBase.java:294)
[junit4:junit4]   2> 		at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:268)
[junit4:junit4]   2> 		at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:693)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 79053 T699 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6004d26c
[junit4:junit4]   2> 79060 T699 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 79060 T699 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 79062 T699 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 79062 T699 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 79107 T699 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 79109 T699 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 79109 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 79109 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 79528 T699 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 79533 T699 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:61863
[junit4:junit4]   2> 79535 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 79535 T699 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-1352002864150
[junit4:junit4]   2> 79536 T699 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-1352002864150\'
[junit4:junit4]   2> 79586 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 79587 T699 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 79587 T699 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-1352002864150
[junit4:junit4]   2> 79587 T699 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-1352002864150\solr.xml
[junit4:junit4]   2> 79587 T699 oasc.CoreContainer.<init> New CoreContainer 986252663
[junit4:junit4]   2> 79589 T699 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-1352002864150\'
[junit4:junit4]   2> 79589 T699 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1352002864150\'
[junit4:junit4]   2> 79614 T699 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 79632 T699 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:61832/solr
[junit4:junit4]   2> 79634 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@188db4c0
[junit4:junit4]   2> 79637 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 79637 T768 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 79639 T768 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 79639 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61868
[junit4:junit4]   2> 79639 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61868
[junit4:junit4]   2> 79669 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0006 with negotiated timeout 5000 for client /127.0.0.1:61868
[junit4:junit4]   2> 79669 T768 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0006, negotiated timeout = 5000
[junit4:junit4]   2> 79669 T769 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@188db4c0 name:ZooKeeperConnection Watcher:127.0.0.1:61832/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 79670 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 79671 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 79673 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 79683 T699 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61863_solr
[junit4:junit4]   2> 79685 T704 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac9a54ecb0006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:61863_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:61863_solr
[junit4:junit4]   2> 79687 T699 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61863_solr
[junit4:junit4]   2> 79694 T733 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 79694 T719 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 79694 T745 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 79696 T757 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 79696 T699 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 79696 T699 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 79696 T699 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 79698 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 79698 T699 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 79698 T699 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 79698 T699 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 79698 T699 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 79698 T699 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 79699 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 79714 T699 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 79723 T699 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-1352002864150\collection1
[junit4:junit4]   2> 79724 T699 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 79725 T699 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 79725 T699 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 79728 T699 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-jetty4-1352002864150\collection1\'
[junit4:junit4]   2> 79729 T699 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty4-1352002864150/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 79730 T699 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solrtest-jetty4-1352002864150/collection1/lib/README' to classloader
[junit4:junit4]   2> 79790 T699 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 79876 T699 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 79881 T699 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 79889 T699 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 80612 T699 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 80622 T699 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 80627 T699 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 80649 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 80655 T699 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 80661 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 80664 T699 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 80666 T699 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-1352002864150\collection1\, dataDir=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/jetty4\
[junit4:junit4]   2> 80666 T699 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb273cc
[junit4:junit4]   2> 80669 T699 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965/jetty4\index/
[junit4:junit4]   2> 80670 T699 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty4\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 80670 T699 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty4\index forceNew:false
[junit4:junit4]   2> 80681 T699 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e54be29; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 80681 T699 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 80684 T699 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 80685 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 80685 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 80685 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 80686 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 80686 T699 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 80686 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 80686 T699 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 80687 T699 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 80687 T699 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 80688 T699 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 80690 T699 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 80690 T699 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 80690 T699 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 80690 T699 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 80691 T699 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 80691 T699 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 80691 T699 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 80692 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 80692 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 80692 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 80692 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 80693 T699 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 80693 T699 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 80693 T699 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 80695 T699 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 80706 T699 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 80711 T699 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 80713 T699 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> 80719 T699 oass.SolrIndexSearcher.<init> Opening Searcher@5fd20a88 main
[junit4:junit4]   2> 80722 T699 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 80723 T699 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 80723 T699 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 80735 T699 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 80805 T770 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5fd20a88 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 80809 T699 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 81276 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 81277 T720 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:61863_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61863/solr"}
[junit4:junit4]   2> 81288 T769 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 81289 T745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 81289 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 81289 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 81443 T699 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 81443 T699 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:61863/solr shardId:shard2
[junit4:junit4]   2> 81450 T699 oasc.ZkController.register We are http://127.0.0.1:61863/solr/collection1/ and leader is http://127.0.0.1:61851/solr/collection1/
[junit4:junit4]   2> 81450 T699 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61863/solr
[junit4:junit4]   2> 81452 T699 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 81452 T699 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C68 name=collection1 org.apache.solr.core.SolrCore@4f707f0c url=http://127.0.0.1:61863/solr/collection1 node=127.0.0.1:61863_solr
[junit4:junit4]   2> 81454 T771 C68 P61863 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 81454 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 81454 T771 C68 P61863 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 81454 T771 C68 P61863 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 81456 T699 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 81456 T699 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 81456 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 81458 T771 C68 P61863 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:61851/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 81458 T771 C68 P61863 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:61863/solr START replicas=[http://127.0.0.1:61851/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 81459 T771 C68 P61863 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 81459 T771 C68 P61863 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 81460 T771 C68 P61863 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 81460 T771 C68 P61863 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 81476 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@201047c5
[junit4:junit4]   2> 81477 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 81481 T773 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 81487 T773 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 81487 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61873
[junit4:junit4]   2> 81488 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61873
[junit4:junit4]   2> ASYNC  NEW_CORE C69 name=collection1 org.apache.solr.core.SolrCore@614c0482 url=http://127.0.0.1:61851/solr/collection1 node=127.0.0.1:61851_solr
[junit4:junit4]   2> 81495 T743 C69 P61851 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=4 
[junit4:junit4]   2> 81495 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0007 with negotiated timeout 10000 for client /127.0.0.1:61873
[junit4:junit4]   2> 81496 T773 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0007, negotiated timeout = 10000
[junit4:junit4]   2> 81496 T774 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@201047c5 name:ZooKeeperConnection Watcher:127.0.0.1:61832/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 81496 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 81499 T699 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 81503 T699 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 81504 T699 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 81512 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C70 name=collection1 org.apache.solr.core.SolrCore@6eaef085 url=http://127.0.0.1:61839/solr/collection1 node=127.0.0.1:61839_solr
[junit4:junit4]   2> 81535 T713 C70 P61839 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ec684b3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 81536 T713 C70 P61839 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 81545 T713 C70 P61839 /update {wt=javabin&version=2} {add=[1 (1417677757729472512)]} 0 21
[junit4:junit4]   2> 81546 T699 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:61832/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@19d45754
[junit4:junit4]   2> 81549 T775 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:61832
[junit4:junit4]   2> 81549 T699 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 81551 T775 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:61832, initiating session
[junit4:junit4]   2> 81551 T701 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:61877
[junit4:junit4]   2> 81551 T701 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:61877
[junit4:junit4]   2> 81555 T703 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac9a54ecb0008 with negotiated timeout 10000 for client /127.0.0.1:61877
[junit4:junit4]   2> 81555 T775 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:61832, sessionid = 0x13ac9a54ecb0008, negotiated timeout = 10000
[junit4:junit4]   2> 81555 T776 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19d45754 name:ZooKeeperConnection Watcher:127.0.0.1:61832/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 81556 T699 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 81557 T699 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C71 name=collection1 org.apache.solr.core.SolrCore@19ad2fd2 url=http://127.0.0.1:61845/solr/collection1 node=127.0.0.1:61845_solr
[junit4:junit4]   2> 81590 T727 C71 P61845 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43e87b75; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 81592 T727 C71 P61845 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 81598 T727 C71 P61845 /update {wt=javabin&version=2} {add=[1 (1417677757789241344)]} 0 15
[junit4:junit4]   2> 81604 T714 C70 P61839 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 82886 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 82890 T714 C70 P61839 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ec684b3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ec684b3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0_Lucene40_0.tim, _0.fnm, _0_Lucene40_0.frq, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene40_0.prx, _0.fdt, _0_Lucene40_0.tip]
[junit4:junit4]   2> 82890 T714 C70 P61839 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 82900 T714 C70 P61839 oass.SolrIndexSearcher.<init> Opening Searcher@31b47245 main
[junit4:junit4]   2> 82902 T714 C70 P61839 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 82903 T720 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:61863_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61863/solr"}
[junit4:junit4]   2> 82905 T722 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@31b47245 main{StandardDirectoryReader(segments_2:3 _0(4.1):C1)}
[junit4:junit4]   2> 82905 T714 C70 P61839 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 1301
[junit4:junit4]   2> 82914 T739 C69 P61851 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 82920 T739 C69 P61851 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5738e61c; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 82921 T739 C69 P61851 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 82926 T739 C69 P61851 oass.SolrIndexSearcher.<init> Opening Searcher@40faaafb main
[junit4:junit4]   2> 82926 T739 C69 P61851 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 82928 T746 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@40faaafb main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 82930 T739 C69 P61851 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:61845/solr/collection1/, StdNode: http://127.0.0.1:61857/solr/collection1/, StdNode: http://127.0.0.1:61863/solr/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2> 82981 T739 C69 P61851 oasc.SolrException.log SEVERE shard update error StdNode: http://127.0.0.1:61857/solr/collection1/:org.apache.solr.common.SolrException: Server at http://127.0.0.1:61857/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/update
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]   2> 		at org.apache.solr.update.SolrCmdDistributor$1.call(SolrCmdDistributor.java:340)
[junit4:junit4]   2> 		at org.apache.solr.update.SolrCmdDistributor$1.call(SolrCmdDistributor.java:314)
[junit4:junit4]   2> 		at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
[junit4:junit4]   2> 		at java.util.concurrent.FutureTask.run(FutureTask.java:138)
[junit4:junit4]   2> 		at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:439)
[junit4:junit4]   2> 		at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
[junit4:junit4]   2> 		at java.util.concurrent.FutureTask.run(FutureTask.java:138)
[junit4:junit4]   2> 		at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
[junit4:junit4]   2> 		at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 82982 T725 C71 P61845 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 83021 T762 C68 P61863 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 83028 T762 C68 P61863 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e54be29; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 83039 T762 C68 P61863 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 83040 T762 C68 P61863 oass.SolrIndexSearcher.<init> Opening Searcher@53481704 main
[junit4:junit4]   2> 83040 T762 C68 P61863 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 83042 T770 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@53481704 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 83044 T769 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 83044 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 83045 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 83046 T745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 83047 T762 C68 P61863 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 28
[junit4:junit4]   2> 83049 T774 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 83050 T776 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 83064 T725 C71 P61845 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43e87b75; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@43e87b75; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0_Lucene40_0.tim, _0.fnm, _0_Lucene40_0.frq, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene40_0.prx, _0.fdt, _0_Lucene40_0.tip]
[junit4:junit4]   2> 83064 T725 C71 P61845 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 83077 T725 C71 P61845 oass.SolrIndexSearcher.<init> Opening Searcher@179691f5 main
[junit4:junit4]   2> 83078 T725 C71 P61845 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 83081 T734 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@179691f5 main{StandardDirectoryReader(segments_2:3 _0(4.1):C1)}
[junit4:junit4]   2> 83082 T725 C71 P61845 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 100
[junit4:junit4]   2> 83084 T739 C69 P61851 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 170
[junit4:junit4]   2> 83085 T699 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):180
[junit4:junit4]   2> 83087 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 84236 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 85371 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C68_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:61863_solr, base_url=http://127.0.0.1:61863/solr}
[junit4:junit4]   2> 86257 T771 C68 P61863 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 86258 T771 C68 P61863 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 86258 T771 C68 P61863 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:61851/solr/collection1/. core=collection1
[junit4:junit4]   2> 86258 T771 C68 P61863 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 86280 T743 C69 P61851 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 86286 T743 C69 P61851 oass.SolrIndexSearcher.<init> Opening Searcher@53e3725d realtime
[junit4:junit4]   2> 86286 T743 C69 P61851 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 86286 T743 C69 P61851 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 6
[junit4:junit4]   2> 86287 T771 C68 P61863 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 86288 T771 C68 P61863 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 86292 T742 C69 P61851 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 86293 T742 C69 P61851 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 86299 T771 C68 P61863 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 86309 T771 C68 P61863 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e54be29; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1352002784965\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e54be29; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 86309 T771 C68 P61863 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 86317 T771 C68 P61863 oass.SolrIndexSearcher.<init> Opening Searcher@31eb6efa main
[junit4:junit4]   2> 86317 T771 C68 P61863 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 86323 T770 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@31eb6efa main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 86324 T771 C68 P61863 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 86324 T771 C68 P61863 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 86324 T771 C68 P61863 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 86328 T771 C68 P61863 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 86507 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 86514 T720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 86516 T720 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:61863_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:61863/solr"}
[junit4:junit4]   2> 86531 T769 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 86531 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 86531 T733 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 86531 T745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 86532 T774 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 86532 T776 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 87641 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 88775 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 89911 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 91045 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 92180 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 93315 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 94423 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 95444 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 96464 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 97485 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 98506 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 99526 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 100549 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 101570 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 102590 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 103611 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 104607 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 105527 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 106446 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 107364 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 108283 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 109201 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 110120 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 111040 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 111959 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 112877 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 113772 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 114599 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 115426 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 116252 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 117080 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 117907 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 118733 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 119560 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 120388 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 121215 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 122019 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 122763 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 123507 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 124251 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 124995 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 125740 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 126484 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 127229 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 127973 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 128718 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 129485 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 130304 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 131123 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 131942 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 132761 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 133580 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 134399 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 135217 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 136035 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 136854 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 137700 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 138600 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 139501 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 140401 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 141301 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 142202 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 143102 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 144002 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 144903 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 145804 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 146735 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 147726 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 148716 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 149708 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 150699 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 151689 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 152679 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 153670 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 154661 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 155653 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 156679 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 157768 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 158858 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 159949 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 161039 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 162130 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 163220 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 164311 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 165401 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 166491 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 167621 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 168820 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 170019 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 171217 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 172416 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 173625 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 174823 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 176022 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 177222 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 178421 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 179672 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 180989 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 182307 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 183625 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 184943 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 186262 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 187579 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 188897 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 190214 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 191533 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 192906 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 194357 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 195808 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 197260 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 198710 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 200161 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 201612 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 203062 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 204513 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 205964 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 207349 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 208653 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 209959 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 211264 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 212569 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 213875 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 215181 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 216485 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 217791 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 219096 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 220341 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 221515 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 222689 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 223863 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 225040 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 226214 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 227389 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 228563 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 229738 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 230913 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 232032 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 233089 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 234146 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 235205 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 236261 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 237320 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 238376 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 239434 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 240492 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 241548 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 242553 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 243505 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 244457 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 245409 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 246360 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 247313 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 248265 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 249216 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 250167 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 251119 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 252022 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 252878 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 253734 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 254590 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 255447 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 256305 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 257160 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 258018 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 258875 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 259731 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 260542 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 261312 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 262083 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 262854 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 263624 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 264395 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 265166 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 265936 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 266707 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 267478 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 268207 T699 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[Finalizer,8,system]
[junit4:junit4]   1> java.lang.Object.wait(Native Method)
[junit4:junit4]   1> java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
[junit4:junit4]   1> java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
[junit4:junit4]   1> java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[HashSessionScavenger-37,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> java.lang.Object.wait(Native Method)
[junit4:junit4]   1> java.util.TimerThread.mainLoop(Timer.java:509)
[junit4:junit4]   1> java.util.TimerThread.run(Timer.java:462)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp153654531-767,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[TEST-BasicDistributedZk2Test.testDistribSearch-seed#[C62887E5C8588B7D]-SendThread(127.0.0.1:61832),5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:273)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:255)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:136)
[junit4:junit4]   1> sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
[junit4:junit4]   1> sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
[junit4:junit4]   1> org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1131)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[HashSessionScavenger-35,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> java.lang.Object.wait(Native Method)
[junit4:junit4]   1> java.util.TimerThread.mainLoop(Timer.java:509)
[junit4:junit4]   1> java.util.TimerThread.run(Timer.java:462)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[TEST-BasicDistributedZk2Test.testDistribSearch-seed#[C62887E5C8588B7D]-EventThread,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
[junit4:junit4]   1> java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
[junit4:junit4]   1> org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[Thread-371,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> java.lang.Object.wait(Native Method)
[junit4:junit4]   1> java.lang.Thread.join(Thread.java:1186)
[junit4:junit4]   1> java.lang.Thread.join(Thread.java:1239)
[junit4:junit4]   1> org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:103)
[junit4:junit4]   1> org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:214)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1571632759-731,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[main,5,main]
[junit4:junit4]   1> java.lang.Object.wait(Native Method)
[junit4:junit4]   1> java.lang.Thread.join(Thread.java:1186)
[junit4:junit4]   1> java.lang.Thread.join(Thread.java:1239)
[junit4:junit4]   1> com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:502)
[junit4:junit4]   1> com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:399)
[junit4:junit4]   1> com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:161)
[junit4:junit4]   1> com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:255)
[junit4:junit4]   1> com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:12)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[SyncThread:0,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
[junit4:junit4]   1> java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
[junit4:junit4]   1> org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:94)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1936125290-714,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1652523905-736 Selector0,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:273)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:255)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:136)
[junit4:junit4]   1> sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
[junit4:junit4]   1> sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
[junit4:junit4]   1> org.eclipse.jetty.io.nio.SelectorManager$SelectSet.doSelect(SelectorManager.java:564)
[junit4:junit4]   1> org.eclipse.jetty.io.nio.SelectorManager$1.run(SelectorManager.java:285)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:599)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:534)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1652523905-739,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Session Sets (11):
[junit4:junit4]   1> 0 expire at Sun Nov 04 00:24:14 EDT 2012:
[junit4:junit4]   1> 0 expire at Sun Nov 04 00:24:15 EDT 2012:
[junit4:junit4]   1> 0 expire at Sun Nov 04 00:24:16 EDT 2012:
[junit4:junit4]   1> 3 expire at Sun Nov 04 00:24:17 EDT 2012:
[junit4:junit4]   1> 	0x13ac9a54ecb0004
[junit4:junit4]   1> 	0x13ac9a54ecb0003
[junit4:junit4]   1> 	0x13ac9a54ecb0006
[junit4:junit4]   1> 1 expire at Sun Nov 04 00:24:18 EDT 2012:
[junit4:junit4]   1> 	0x13ac9a54ecb0005
[junit4:junit4]   1> 1 expire at Sun Nov 04 00:24:19 EDT 2012:
[junit4:junit4]   1> 	0x13ac9a54ecb0002
[junit4:junit4]   1> 0 expire at Sun Nov 04 00:24:20 EDT 2012:
[junit4:junit4]   1> 0 expire at Sun Nov 04 00:24:21 EDT 2012:
[junit4:junit4]   1> 1 expire at Sun Nov 04 00:24:22 EDT 2012:
[junit4:junit4]   1> 	0x13ac9a54ecb0008
[junit4:junit4]   1> 0 expire at Sun Nov 04 00:24:23 EDT 2012:
[junit4:junit4]   1> 1 expire at Sun Nov 04 00:24:24 EDT 2012:
[junit4:junit4]   1> 	0x13ac9a54ecb0007
[junit4:junit4]   1> 
[junit4:junit4]   1> java.lang.Object.wait(Native Method)
[junit4:junit4]   1> org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp153654531-761 Acceptor0 SelectChannelConnector@0.0.0.0:61863,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method)
[junit4:junit4]   1> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:150)
[junit4:junit4]   1> org.eclipse.jetty.server.nio.SelectChannelConnector.accept(SelectChannelConnector.java:104)
[junit4:junit4]   1> org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:933)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:599)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:534)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[TEST-BasicDistributedZk2Test.testDistribSearch-seed#[C62887E5C8588B7D]-EventThread,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
[junit4:junit4]   1> java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
[junit4:junit4]   1> org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1571632759-728,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[TEST-BasicDistributedZk2Test.testDistribSearch-seed#[C62887E5C8588B7D]-EventThread,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
[junit4:junit4]   1> java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
[junit4:junit4]   1> org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp153654531-762,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[Attach Listener,5,system]
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[SUITE-BasicDistributedZk2Test-seed#[C62887E5C8588B7D],5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> java.lang.Object.wait(Native Method)
[junit4:junit4]   1> java.lang.Thread.join(Thread.java:1194)
[junit4:junit4]   1> com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:787)
[junit4:junit4]   1> com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:385)
[junit4:junit4]   1> com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:556)
[junit4:junit4]   1> com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:79)
[junit4:junit4]   1> com.carrotsearch.randomizedtesting.RandomizedRunner$1.run(RandomizedRunner.java:492)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:273)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:255)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:136)
[junit4:junit4]   1> sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
[junit4:junit4]   1> sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
[junit4:junit4]   1> org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:232)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[searcherExecutor-506-thread-1,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
[junit4:junit4]   1> java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
[junit4:junit4]   1> java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:947)
[junit4:junit4]   1> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1571632759-726 Acceptor0 SelectChannelConnector@0.0.0.0:61845,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method)
[junit4:junit4]   1> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:150)
[junit4:junit4]   1> org.eclipse.jetty.server.nio.SelectChannelConnector.accept(SelectChannelConnector.java:104)
[junit4:junit4]   1> org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:933)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:599)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:534)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1936125290-715 Acceptor0 SelectChannelConnector@0.0.0.0:61839,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method)
[junit4:junit4]   1> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:150)
[junit4:junit4]   1> org.eclipse.jetty.server.nio.SelectChannelConnector.accept(SelectChannelConnector.java:104)
[junit4:junit4]   1> org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:933)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:599)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:534)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1571632759-730,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[TEST-BasicDistributedZk2Test.testDistribSearch-seed#[C62887E5C8588B7D]-EventThread,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
[junit4:junit4]   1> java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
[junit4:junit4]   1> org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1571632759-725,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1652523905-738 Acceptor0 SelectChannelConnector@0.0.0.0:61851,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method)
[junit4:junit4]   1> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:150)
[junit4:junit4]   1> org.eclipse.jetty.server.nio.SelectChannelConnector.accept(SelectChannelConnector.java:104)
[junit4:junit4]   1> org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:933)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:599)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:534)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1652523905-742,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[TEST-BasicDistributedZk2Test.testDistribSearch-seed#[C62887E5C8588B7D]-SendThread(127.0.0.1:61832),5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:273)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:255)
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:136)
[junit4:junit4]   1> sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
[junit4:junit4]   1> sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
[junit4:junit4]   1> org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1131)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[TEST-BasicDistributedZk2Test.testDistribSearch-seed#[C62887E5C8588B7D]-EventThread,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
[junit4:junit4]   1> java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
[junit4:junit4]   1> org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp1652523905-737,5,TGRP-BasicDistributedZk2Test]
[junit4:junit4]   1> sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   1> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
[junit4:junit4]   1> org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:337)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:517)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:39)
[junit4:junit4]   1> org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:563)
[junit4:junit4]   1>

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

      "base_url":"http://127.0.0.1:61851/solr",
[junit4:junit4]   1>                 "leader":"true"},
[junit4:junit4]   1>               "127.0.0.1:61863_solr_collection1":{
[junit4:junit4]   1>                 "shard":"shard2",
[junit4:junit4]   1>                 "roles":null,
[junit4:junit4]   1>                 "state":"active",
[junit4:junit4]   1>                 "core":"collection1",
[junit4:junit4]   1>                 "collection":"collection1",
[junit4:junit4]   1>                 "node_name":"127.0.0.1:61863_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:61863/solr"}}}},
[junit4:junit4]   1>         "control_collection":{"control_shard":{"replicas":{"127.0.0.1:61839_solr_collection1":{
[junit4:junit4]   1>                 "shard":"control_shard",
[junit4:junit4]   1>                 "roles":null,
[junit4:junit4]   1>                 "state":"active",
[junit4:junit4]   1>                 "core":"collection1",
[junit4:junit4]   1>                 "collection":"control_collection",
[junit4:junit4]   1>                 "node_name":"127.0.0.1:61839_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:61839/solr",
[junit4: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=BasicDistributedZk2Test -Dtests.method=testDistribSearch -Dtests.seed=C62887E5C8588B7D -Dtests.slow=true -Dtests.locale=sr -Dtests.timezone=US/East-Indiana -Dtests.file.encoding=US-ASCII
[junit4:junit4] FAILURE  274s | BasicDistributedZk2Test.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 180 seconds
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([C62887E5C8588B7D:47CE09FDBF07EB41]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:165)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:115)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:110)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:553)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:65)
[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:39)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$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:662)
[junit4:junit4]   2> 274186 T699 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=sr, timezone=US/East-Indiana
[junit4:junit4]   2> NOTE: Windows 7 6.1 amd64/Sun Microsystems Inc. 1.6.0_37 (64-bit)/cpus=2,threads=1,free=11153296,total=46387200
[junit4:junit4]   2> NOTE: All tests run in this JVM: [FileUtilsTest, StatelessScriptUpdateProcessorFactoryTest, TestReplicationHandler, TestSolrIndexConfig, TestPhraseSuggestions, XsltUpdateRequestHandlerTest, TestRemoteStreaming, AutoCommitTest, DirectUpdateHandlerOptimizeTest, TestTrie, SpellCheckComponentTest, TestPerFieldSimilarity, TestSearchPerf, TestValueSourceCache, IndexBasedSpellCheckerTest, TestSolrCoreProperties, TermVectorComponentDistributedTest, EchoParamsTest, SignatureUpdateProcessorFactoryTest, TestStressRecovery, SolrCoreTest, TestCharFilters, FullSolrCloudDistribCmdsTest, TestRecovery, TestFaceting, TestSuggestSpellingConverter, TestBadConfig, TestSystemIdResolver, DisMaxRequestHandlerTest, TestStressVersions, ConvertedLegacyTest, TestLFUCache, TestSort, TestJmxIntegration, BasicDistributedZk2Test]
[junit4:junit4] Completed in 274.20s, 1 test, 1 failure <<< FAILURES!

[...truncated 613 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:1163: 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: 43 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.6.0_37 -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message