lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_37) - Build # 1448 - Failure!
Date Mon, 05 Nov 2012 22:42:24 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/1448/
Java: 32bit/jdk1.6.0_37 -client -XX:+UseSerialGC

1 tests failed.
REGRESSION:  org.apache.solr.client.solrj.impl.CloudSolrServerTest.testDistribSearch

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

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 30 seconds
	at __randomizedtesting.SeedInfo.seed([1FE83E3422B02A8F:9E0EB02C55EF4AB3]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:165)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:558)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1133)
	at org.apache.solr.client.solrj.impl.CloudSolrServerTest.doTest(CloudSolrServerTest.java:95)
	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 8676 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest
[junit4:junit4]   2> 8 T70 oasc.AbstractZkTestCase.<clinit> WARNING TEST_HOME() does not exist - solrj test?
[junit4:junit4]   2> 12 T70 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-CloudSolrServerTest-1352155101537
[junit4:junit4]   2> 21 T70 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 95 T71 oaz.Environment.logEnv Server environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 95 T71 oaz.Environment.logEnv Server environment:host.name=JenkinsSlave-PC
[junit4:junit4]   2> 95 T71 oaz.Environment.logEnv Server environment:java.version=1.6.0_37
[junit4:junit4]   2> 95 T71 oaz.Environment.logEnv Server environment:java.vendor=Sun Microsystems Inc.
[junit4:junit4]   2> 96 T71 oaz.Environment.logEnv Server environment:java.home=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\jre
[junit4:junit4]   2> 96 T71 oaz.Environment.logEnv Server environment:java.class.path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\classes\test;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\codecs\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\commons-io-2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\httpclient-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\httpcore-4.1.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\httpmime-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\jcl-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\log4j-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\slf4j-api-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\slf4j-jdk14-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\wstx-asl-3.2.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\zookeeper-3.3.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\test-framework\lib\junit-4.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\test-framework\lib\randomizedtesting-runner-2.0.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\analysis\common\lucene-analyzers-common-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\analysis\kuromoji\lucene-analyzers-kuromoji-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\analysis\phonetic\lucene-analyzers-phonetic-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\highlighter\lucene-highlighter-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\memory\lucene-memory-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\misc\lucene-misc-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\spatial\lucene-spatial-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\suggest\lucene-suggest-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\grouping\lucene-grouping-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\queries\lucene-queries-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\queryparser\lucene-queryparser-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\commons-cli-1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\commons-codec-1.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\commons-fileupload-1.2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\commons-lang-2.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\easymock-2.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\guava-r05.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\javax.servlet-api-3.0.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\spatial4j-0.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-continuation-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-deploy-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-http-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-io-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-jmx-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-security-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-server-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-servlet-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-util-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-webapp-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-xml-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\servlet-api-3.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\example-DIH\solr\db\lib\hsqldb-1.8.0.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\core\classes\java;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-launcher.jar;C:\Users\JenkinsSlave\.ant\lib\ivy-2.2.0.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-antlr.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-bcel.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-bsf.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-log4j.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-oro.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-regexp.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-resolver.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-xalan2.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-commons-logging.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-commons-net.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-jai.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-javamail.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-jdepend.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-jmf.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-jsch.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-junit.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-junit4.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-netrexx.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-swing.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-testutil.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant.jar;C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\lib\tools.jar;C:\Users\JenkinsSlave\.ivy2\cache\com.carrotsearch.randomizedtesting\junit4-ant\jars\junit4-ant-2.0.4.jar
[junit4:junit4]   2> 97 T71 oaz.Environment.logEnv Server environment:java.library.path=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\jre\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Subversion\bin;.
[junit4:junit4]   2> 97 T71 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 97 T71 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4]   2> 97 T71 oaz.Environment.logEnv Server environment:os.name=Windows 7
[junit4:junit4]   2> 97 T71 oaz.Environment.logEnv Server environment:os.arch=x86
[junit4:junit4]   2> 98 T71 oaz.Environment.logEnv Server environment:os.version=6.1
[junit4:junit4]   2> 98 T71 oaz.Environment.logEnv Server environment:user.name=JenkinsSlave
[junit4:junit4]   2> 98 T71 oaz.Environment.logEnv Server environment:user.home=C:\Users\JenkinsSlave
[junit4:junit4]   2> 98 T71 oaz.Environment.logEnv Server environment:user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 122 T71 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 141 T71 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 148 T70 oasc.ZkTestServer.run start zk server on port:56754
[junit4:junit4]   2> 236 T71 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 266 T70 oaz.Environment.logEnv Client environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 267 T70 oaz.Environment.logEnv Client environment:host.name=JenkinsSlave-PC
[junit4:junit4]   2> 268 T70 oaz.Environment.logEnv Client environment:java.version=1.6.0_37
[junit4:junit4]   2> 268 T70 oaz.Environment.logEnv Client environment:java.vendor=Sun Microsystems Inc.
[junit4:junit4]   2> 268 T70 oaz.Environment.logEnv Client environment:java.home=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\jre
[junit4:junit4]   2> 268 T70 oaz.Environment.logEnv Client environment:java.class.path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\classes\test;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\codecs\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\commons-io-2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\httpclient-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\httpcore-4.1.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\httpmime-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\jcl-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\log4j-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\slf4j-api-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\slf4j-jdk14-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\wstx-asl-3.2.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\lib\zookeeper-3.3.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\test-framework\lib\junit-4.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\test-framework\lib\randomizedtesting-runner-2.0.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\analysis\common\lucene-analyzers-common-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\analysis\kuromoji\lucene-analyzers-kuromoji-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\analysis\phonetic\lucene-analyzers-phonetic-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\highlighter\lucene-highlighter-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\memory\lucene-memory-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\misc\lucene-misc-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\spatial\lucene-spatial-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\suggest\lucene-suggest-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\grouping\lucene-grouping-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\queries\lucene-queries-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\queryparser\lucene-queryparser-4.1-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\commons-cli-1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\commons-codec-1.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\commons-fileupload-1.2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\commons-lang-2.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\easymock-2.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\guava-r05.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\javax.servlet-api-3.0.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\lib\spatial4j-0.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-continuation-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-deploy-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-http-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-io-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-jmx-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-security-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-server-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-servlet-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-util-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-webapp-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\jetty-xml-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\lib\servlet-api-3.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\example-DIH\solr\db\lib\hsqldb-1.8.0.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\core\classes\java;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-launcher.jar;C:\Users\JenkinsSlave\.ant\lib\ivy-2.2.0.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-antlr.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-bcel.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-bsf.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-log4j.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-oro.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-regexp.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-resolver.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-apache-xalan2.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-commons-logging.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-commons-net.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-jai.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-javamail.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-jdepend.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-jmf.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-jsch.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-junit.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-junit4.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-netrexx.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-swing.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant-testutil.jar;C:\Users\JenkinsSlave\tools\Ant\ANT_1.8.2\lib\ant.jar;C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\lib\tools.jar;C:\Users\JenkinsSlave\.ivy2\cache\com.carrotsearch.randomizedtesting\junit4-ant\jars\junit4-ant-2.0.4.jar
[junit4:junit4]   2> 272 T70 oaz.Environment.logEnv Client environment:java.library.path=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\jre\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Subversion\bin;.
[junit4:junit4]   2> 272 T70 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 272 T70 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4]   2> 274 T70 oaz.Environment.logEnv Client environment:os.name=Windows 7
[junit4:junit4]   2> 274 T70 oaz.Environment.logEnv Client environment:os.arch=x86
[junit4:junit4]   2> 274 T70 oaz.Environment.logEnv Client environment:os.version=6.1
[junit4:junit4]   2> 275 T70 oaz.Environment.logEnv Client environment:user.name=JenkinsSlave
[junit4:junit4]   2> 275 T70 oaz.Environment.logEnv Client environment:user.home=C:\Users\JenkinsSlave
[junit4:junit4]   2> 277 T70 oaz.Environment.logEnv Client environment:user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 281 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@20b357
[junit4:junit4]   2> 365 T74 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 370 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56757
[junit4:junit4]   2> 370 T74 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 379 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 411 T72 oazs.NIOServerCnxn.doIO WARNING Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
[junit4:junit4]   2> 411 T72 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56757 (no session established for client)
[junit4:junit4]   2> 412 T74 oaz.ClientCnxn$SendThread.run Unable to read additional data from server sessionid 0x0, likely server has closed socket, closing socket connection and attempting reconnect
[junit4:junit4]   2> 2316 T74 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:56754
[junit4:junit4]   2> 2316 T74 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 2317 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56758
[junit4:junit4]   2> 2317 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56758
[junit4:junit4]   2> 2329 T75 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 2361 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810000 with negotiated timeout 10000 for client /127.0.0.1:56758
[junit4:junit4]   2> 2361 T74 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810000, negotiated timeout = 10000
[junit4:junit4]   2> 2364 T76 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@20b357 name:ZooKeeperConnection Watcher:127.0.0.1:56754 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2364 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2368 T70 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 2427 T77 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ad2b97a810000
[junit4:junit4]   2> 2429 T70 oaz.ZooKeeper.close Session: 0x13ad2b97a810000 closed
[junit4:junit4]   2> 2431 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1e8c706
[junit4:junit4]   2> 2432 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2429 T76 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 2433 T72 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56758 which had sessionid 0x13ad2b97a810000
[junit4:junit4]   2> 2433 T78 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 2435 T78 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 2436 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56761
[junit4:junit4]   2> 2436 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56761
[junit4:junit4]   2> 2439 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810001 with negotiated timeout 10000 for client /127.0.0.1:56761
[junit4:junit4]   2> 2439 T78 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810001, negotiated timeout = 10000
[junit4:junit4]   2> 2439 T79 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e8c706 name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2440 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2452 T70 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 2461 T70 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 2467 T70 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 2472 T70 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 2479 T70 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2479 T70 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2488 T70 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 2490 T70 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 2497 T70 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2498 T70 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2512 T70 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\stopwords.txt because it doesn't exist
[junit4:junit4]   2> 2512 T70 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\protwords.txt because it doesn't exist
[junit4:junit4]   2> 2513 T70 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\currency.xml because it doesn't exist
[junit4:junit4]   2> 2513 T70 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\open-exchange-rates.json because it doesn't exist
[junit4:junit4]   2> 2513 T70 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4:junit4]   2> 2514 T70 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\old_synonyms.txt because it doesn't exist
[junit4:junit4]   2> 2514 T70 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\synonyms.txt because it doesn't exist
[junit4:junit4]   2> 2516 T77 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ad2b97a810001
[junit4:junit4]   2> 2517 T70 oaz.ZooKeeper.close Session: 0x13ad2b97a810001 closed
[junit4:junit4]   2> 2518 T72 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56761 which had sessionid 0x13ad2b97a810001
[junit4:junit4]   2> 2518 T79 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 2518 T70 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2531 T70 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:56762
[junit4:junit4]   2> 2531 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2532 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr
[junit4:junit4]   2> 2532 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\'
[junit4:junit4]   2> 2578 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2579 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2579 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr
[junit4:junit4]   2> 2579 T70 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\solr.xml
[junit4:junit4]   2> 2581 T70 oasc.CoreContainer.<init> New CoreContainer 30441432
[junit4:junit4]   2> 2581 T70 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\'
[junit4:junit4]   2> 2582 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\'
[junit4:junit4]   2> 2611 T70 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2637 T70 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56754/solr
[junit4:junit4]   2> 2656 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a46a8f
[junit4:junit4]   2> 2659 T89 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 2659 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2662 T89 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 2662 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56767
[junit4:junit4]   2> 2663 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56767
[junit4:junit4]   2> 2666 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810002 with negotiated timeout 8000 for client /127.0.0.1:56767
[junit4:junit4]   2> 2666 T89 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810002, negotiated timeout = 8000
[junit4:junit4]   2> 2667 T90 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a46a8f name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2667 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2691 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2699 T70 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 2704 T70 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56762_solr
[junit4:junit4]   2> 2707 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56762_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56762_solr
[junit4:junit4]   2> 2709 T70 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56762_solr
[junit4:junit4]   2> 2714 T70 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2715 T70 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2715 T70 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2715 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2715 T70 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2715 T70 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2715 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2717 T70 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2717 T70 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2717 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2725 T70 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 2750 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 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> 2751 T70 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 2756 T70 oasc.Overseer.start Overseer (id=88614836753203202-127.0.0.1:56762_solr-n_0000000000) starting
[junit4:junit4]   2> 2757 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2766 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2770 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2776 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2778 T92 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 2779 T70 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 2784 T70 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2830 T91 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 2832 T70 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1
[junit4:junit4]   2> 2834 T70 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 2836 T70 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2836 T70 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 2838 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\'
[junit4:junit4]   2> 2921 T70 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 3015 T70 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3018 T70 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3028 T70 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4076 T70 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4081 T70 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4086 T70 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 4086 T70 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 4096 T70 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/control/data\
[junit4:junit4]   2> 4096 T70 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 4128 T70 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/control/data\index/
[junit4:junit4]   2> 4130 T70 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4131 T70 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\control\data\index forceNew:false
[junit4:junit4]   2> 4144 T70 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\control\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@ab3ce3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4144 T70 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4147 T70 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4147 T70 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4147 T70 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4148 T70 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4148 T70 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4149 T70 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4157 T70 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4168 T70 oass.SolrIndexSearcher.<init> Opening Searcher@13bed88 main
[junit4:junit4]   2> 4171 T70 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4172 T70 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4174 T93 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13bed88 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4174 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4176 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x39 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 4181 T70 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4181 T70 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56762/solr shardId:control_shard
[junit4:junit4]   2> 4190 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 4197 T70 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 4211 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:delete cxid:0x48 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> 4213 T70 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4213 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x49 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4584 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4585 T91 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:56762_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56762/solr"}
[junit4:junit4]   2> 4593 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 4651 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 4763 T70 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4764 T70 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4765 T70 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:56762/solr/collection1/
[junit4:junit4]   2> 4766 T70 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4767 T70 oasc.SyncStrategy.syncToMe http://127.0.0.1:56762/solr/collection1/ has no replicas
[junit4:junit4]   2> 4768 T70 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56762/solr/collection1/
[junit4:junit4]   2> 4769 T70 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 4834 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810002 type:create cxid:0x6e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5264 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5336 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 5395 T70 oasc.ZkController.register We are http://127.0.0.1:56762/solr/collection1/ and leader is http://127.0.0.1:56762/solr/collection1/
[junit4:junit4]   2> 5396 T70 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56762/solr
[junit4:junit4]   2> 5397 T70 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5398 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5435 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5437 T70 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 5438 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5438 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5700 T70 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5705 T70 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:56768
[junit4:junit4]   2> 5706 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5706 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974
[junit4:junit4]   2> 5707 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974\'
[junit4:junit4]   2> 5740 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5740 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5740 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974
[junit4:junit4]   2> 5740 T70 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974\solr.xml
[junit4:junit4]   2> 5741 T70 oasc.CoreContainer.<init> New CoreContainer 5575030
[junit4:junit4]   2> 5742 T70 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974\'
[junit4:junit4]   2> 5742 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974\'
[junit4:junit4]   2> 5768 T70 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5788 T70 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56754/solr
[junit4:junit4]   2> 5789 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@614070
[junit4:junit4]   2> 5790 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5790 T103 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 5792 T103 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 5792 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56773
[junit4:junit4]   2> 5793 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56773
[junit4:junit4]   2> 5801 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810003 with negotiated timeout 8000 for client /127.0.0.1:56773
[junit4:junit4]   2> 5801 T103 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810003, negotiated timeout = 8000
[junit4:junit4]   2> 5802 T104 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@614070 name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5802 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5803 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5805 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5807 T70 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56768_solr
[junit4:junit4]   2> 5808 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56768_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56768_solr
[junit4:junit4]   2> 5809 T70 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56768_solr
[junit4:junit4]   2> 5815 T90 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 5816 T70 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5816 T70 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5823 T70 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5823 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5824 T70 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5824 T70 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5824 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5824 T70 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5824 T70 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5825 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5840 T70 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5851 T70 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974\collection1
[junit4:junit4]   2> 5851 T70 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5852 T70 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5852 T70 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5855 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974\collection1\'
[junit4:junit4]   2> 5903 T70 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 5907 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5909 T91 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:56762_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56762/solr"}
[junit4:junit4]   2> 5921 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5922 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5981 T70 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5983 T70 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5989 T70 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6686 T70 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6690 T70 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6693 T70 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 6693 T70 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 6694 T70 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty1-1352155106974\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty1\
[junit4:junit4]   2> 6695 T70 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 6696 T70 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty1\index/
[junit4:junit4]   2> 6697 T70 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6698 T70 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty1\index forceNew:false
[junit4:junit4]   2> 6711 T70 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@198e232; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6711 T70 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6713 T70 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6714 T70 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6714 T70 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6714 T70 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6715 T70 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6715 T70 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6723 T70 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6735 T70 oass.SolrIndexSearcher.<init> Opening Searcher@1c81f66 main
[junit4:junit4]   2> 6737 T70 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6737 T70 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6740 T105 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c81f66 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6740 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7022 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7023 T91 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:56768_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56768/solr"}
[junit4:junit4]   2> 7024 T91 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 7039 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 7039 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 7288 T70 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7288 T70 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56768/solr shardId:shard1
[junit4:junit4]   2> 7289 T70 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 7304 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810003 type:delete cxid:0x26 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 7306 T70 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7307 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810003 type:create cxid:0x27 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7311 T70 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7312 T70 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7312 T70 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:56768/solr/collection1/
[junit4:junit4]   2> 7312 T70 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7313 T70 oasc.SyncStrategy.syncToMe http://127.0.0.1:56768/solr/collection1/ has no replicas
[junit4:junit4]   2> 7313 T70 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56768/solr/collection1/
[junit4:junit4]   2> 7313 T70 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 7324 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810003 type:create cxid:0x31 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7591 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7605 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 7606 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 7660 T70 oasc.ZkController.register We are http://127.0.0.1:56768/solr/collection1/ and leader is http://127.0.0.1:56768/solr/collection1/
[junit4:junit4]   2> 7660 T70 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56768/solr
[junit4:junit4]   2> 7661 T70 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7661 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7664 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7665 T70 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 7665 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7666 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7865 T70 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 7868 T70 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:56774
[junit4:junit4]   2> 7868 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7869 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197
[junit4:junit4]   2> 7870 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197\'
[junit4:junit4]   2> 7908 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7908 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7908 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197
[junit4:junit4]   2> 7908 T70 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197\solr.xml
[junit4:junit4]   2> 7909 T70 oasc.CoreContainer.<init> New CoreContainer 4755454
[junit4:junit4]   2> 7909 T70 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197\'
[junit4:junit4]   2> 7909 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197\'
[junit4:junit4]   2> 7932 T70 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 7965 T70 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56754/solr
[junit4:junit4]   2> 7966 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@32ddc5
[junit4:junit4]   2> 7968 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7969 T115 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 7970 T115 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 7973 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56779
[junit4:junit4]   2> 7973 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56779
[junit4:junit4]   2> 7978 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810004 with negotiated timeout 8000 for client /127.0.0.1:56779
[junit4:junit4]   2> 7979 T115 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810004, negotiated timeout = 8000
[junit4:junit4]   2> 7979 T116 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32ddc5 name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7980 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7983 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7985 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7988 T70 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56774_solr
[junit4:junit4]   2> 7990 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56774_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56774_solr
[junit4:junit4]   2> 7991 T70 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56774_solr
[junit4:junit4]   2> 7996 T90 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 7996 T104 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 7997 T70 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 7998 T70 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7998 T70 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 7999 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7999 T70 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8000 T70 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8000 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8001 T70 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8001 T70 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8002 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8021 T70 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8029 T70 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197\collection1
[junit4:junit4]   2> 8029 T70 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8031 T70 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8032 T70 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8034 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197\collection1\'
[junit4:junit4]   2> 8083 T70 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 8147 T70 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8149 T70 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8155 T70 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8159 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8160 T91 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:56768_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56768/solr"}
[junit4:junit4]   2> 8179 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8179 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8179 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8884 T70 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8888 T70 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8890 T70 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 8891 T70 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 8891 T70 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty2-1352155109197\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty2\
[junit4:junit4]   2> 8892 T70 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 8894 T70 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty2\index/
[junit4:junit4]   2> 8895 T70 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8896 T70 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty2\index forceNew:false
[junit4:junit4]   2> 8906 T70 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ade3c6; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8906 T70 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 8909 T70 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8909 T70 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 8909 T70 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 8911 T70 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8911 T70 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8912 T70 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8918 T70 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8930 T70 oass.SolrIndexSearcher.<init> Opening Searcher@406ccb main
[junit4:junit4]   2> 8932 T70 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8932 T70 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8935 T117 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@406ccb main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8935 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9278 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9280 T91 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:56774_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56774/solr"}
[junit4:junit4]   2> 9294 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 9294 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 9294 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 9482 T70 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 9482 T70 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56774/solr shardId:shard2
[junit4:junit4]   2> 9483 T70 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 9494 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810004 type:delete cxid:0x25 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> 9497 T70 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 9498 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810004 type:create cxid:0x26 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9501 T70 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 9502 T70 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 9502 T70 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:56774/solr/collection1/
[junit4:junit4]   2> 9502 T70 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 9503 T70 oasc.SyncStrategy.syncToMe http://127.0.0.1:56774/solr/collection1/ has no replicas
[junit4:junit4]   2> 9503 T70 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56774/solr/collection1/
[junit4:junit4]   2> 9503 T70 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 9509 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810004 type:create cxid:0x2f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9847 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9913 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 9913 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 9913 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 9956 T70 oasc.ZkController.register We are http://127.0.0.1:56774/solr/collection1/ and leader is http://127.0.0.1:56774/solr/collection1/
[junit4:junit4]   2> 9956 T70 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56774/solr
[junit4:junit4]   2> 9956 T70 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 9956 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9983 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9984 T70 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 9984 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 9985 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10193 T70 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 10198 T70 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:56780
[junit4:junit4]   2> 10199 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 10201 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518
[junit4:junit4]   2> 10201 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518\'
[junit4:junit4]   2> 10240 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 10240 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 10240 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518
[junit4:junit4]   2> 10241 T70 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518\solr.xml
[junit4:junit4]   2> 10241 T70 oasc.CoreContainer.<init> New CoreContainer 25630291
[junit4:junit4]   2> 10241 T70 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518\'
[junit4:junit4]   2> 10241 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518\'
[junit4:junit4]   2> 10265 T70 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 10285 T70 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56754/solr
[junit4:junit4]   2> 10285 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1cbffec
[junit4:junit4]   2> 10286 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10288 T127 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 10290 T127 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 10290 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56785
[junit4:junit4]   2> 10290 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56785
[junit4:junit4]   2> 10298 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810005 with negotiated timeout 8000 for client /127.0.0.1:56785
[junit4:junit4]   2> 10298 T127 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810005, negotiated timeout = 8000
[junit4:junit4]   2> 10298 T128 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cbffec name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10299 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10301 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 10303 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 10305 T70 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56780_solr
[junit4:junit4]   2> 10306 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56780_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56780_solr
[junit4:junit4]   2> 10308 T70 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56780_solr
[junit4:junit4]   2> 10313 T116 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 10313 T104 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 10313 T70 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 10313 T90 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 10313 T70 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 10314 T70 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 10315 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 10315 T70 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 10315 T70 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 10315 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 10315 T70 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 10316 T70 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 10316 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 10328 T70 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10336 T70 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518\collection1
[junit4:junit4]   2> 10336 T70 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 10337 T70 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 10337 T70 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 10339 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518\collection1\'
[junit4:junit4]   2> 10392 T70 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 10449 T70 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 10452 T70 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 10463 T70 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 10506 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10507 T91 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:56774_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56774/solr"}
[junit4:junit4]   2> 10515 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 10517 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 10519 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 10519 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 11196 T70 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 11199 T70 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 11201 T70 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 11201 T70 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 11203 T70 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty3-1352155111518\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty3\
[junit4:junit4]   2> 11203 T70 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 11206 T70 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty3\index/
[junit4:junit4]   2> 11207 T70 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty3\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 11208 T70 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty3\index forceNew:false
[junit4:junit4]   2> 11217 T70 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2e77d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11217 T70 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 11219 T70 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 11220 T70 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 11220 T70 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 11220 T70 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 11222 T70 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 11222 T70 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 11229 T70 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 11241 T70 oass.SolrIndexSearcher.<init> Opening Searcher@62dd8b main
[junit4:junit4]   2> 11243 T70 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 11243 T70 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 11247 T129 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62dd8b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 11247 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11620 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11621 T91 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:56780_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56780/solr"}
[junit4:junit4]   2> 11629 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 11629 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 11629 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 11630 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 11795 T70 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 11795 T70 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56780/solr shardId:shard1
[junit4:junit4]   2> 11803 T70 oasc.ZkController.register We are http://127.0.0.1:56780/solr/collection1/ and leader is http://127.0.0.1:56768/solr/collection1/
[junit4:junit4]   2> 11803 T70 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56780/solr
[junit4:junit4]   2> 11803 T70 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 11804 T70 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C12 name=collection1 org.apache.solr.core.SolrCore@158b45 url=http://127.0.0.1:56780/solr/collection1 node=127.0.0.1:56780_solr
[junit4:junit4]   2> 11806 T130 C12 P56780 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 11808 T130 C12 P56780 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 11806 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11808 T130 C12 P56780 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11809 T70 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 11810 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 11810 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 11812 T130 C12 P56780 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:56768/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 11820 T130 C12 P56780 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 11831 T130 C12 P56780 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:56780/solr START replicas=[http://127.0.0.1:56768/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 11832 T130 C12 P56780 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 11844 T130 C12 P56780 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 11844 T130 C12 P56780 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 11844 T130 C12 P56780 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C13 name=collection1 org.apache.solr.core.SolrCore@1689c98 url=http://127.0.0.1:56768/solr/collection1 node=127.0.0.1:56768_solr
[junit4:junit4]   2> 11855 T99 C13 P56768 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=2 
[junit4:junit4]   2> 12036 T70 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 12040 T70 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:56788
[junit4:junit4]   2> 12041 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12043 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342
[junit4:junit4]   2> 12043 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342\'
[junit4:junit4]   2> 12085 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 12086 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12086 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342
[junit4:junit4]   2> 12086 T70 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342\solr.xml
[junit4:junit4]   2> 12087 T70 oasc.CoreContainer.<init> New CoreContainer 19142417
[junit4:junit4]   2> 12087 T70 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342\'
[junit4:junit4]   2> 12087 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342\'
[junit4:junit4]   2> 12116 T70 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 12138 T70 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56754/solr
[junit4:junit4]   2> 12139 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@13cb3fc
[junit4:junit4]   2> 12140 T141 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 12140 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12143 T141 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 12143 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56793
[junit4:junit4]   2> 12143 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56793
[junit4:junit4]   2> 12152 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810006 with negotiated timeout 8000 for client /127.0.0.1:56793
[junit4:junit4]   2> 12152 T141 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810006, negotiated timeout = 8000
[junit4:junit4]   2> 12152 T142 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13cb3fc name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12153 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12154 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12156 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12158 T70 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56788_solr
[junit4:junit4]   2> 12160 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56788_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56788_solr
[junit4:junit4]   2> 12162 T70 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56788_solr
[junit4:junit4]   2> 12166 T90 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 12166 T128 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 12167 T116 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 12167 T104 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 12167 T70 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 12167 T70 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12168 T70 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 12168 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12169 T70 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12169 T70 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12170 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12170 T70 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12171 T70 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12171 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 12182 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12183 T91 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:56780_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56780/solr"}
[junit4:junit4]   2> 12194 T70 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 12198 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12199 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12199 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12200 T142 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> 12200 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12213 T70 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342\collection1
[junit4:junit4]   2> 12213 T70 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 12214 T70 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 12214 T70 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 12217 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342\collection1\'
[junit4:junit4]   2> 12275 T70 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 12336 T70 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 12338 T70 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 12342 T70 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 13062 T70 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 13068 T70 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 13070 T70 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 13070 T70 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 13071 T70 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty4-1352155113342\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty4\
[junit4:junit4]   2> 13071 T70 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 13072 T70 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty4\index/
[junit4:junit4]   2> 13073 T70 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty4\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 13073 T70 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty4\index forceNew:false
[junit4:junit4]   2> 13087 T70 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty4\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@59b3e1; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13088 T70 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 13090 T70 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 13091 T70 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 13091 T70 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 13091 T70 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 13092 T70 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 13092 T70 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 13101 T70 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 13112 T70 oass.SolrIndexSearcher.<init> Opening Searcher@1d08aaf main
[junit4:junit4]   2> 13115 T70 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 13115 T70 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 13117 T143 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d08aaf main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 13117 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 13304 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13305 T91 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:56788_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56788/solr"}
[junit4:junit4]   2> 13312 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 13312 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 13312 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 13312 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2>  C12_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:56780_solr, base_url=http://127.0.0.1:56780/solr}
[junit4:junit4]   2> 15093 T130 C12 P56780 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 15093 T130 C12 P56780 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 15093 T130 C12 P56780 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:56768/solr/collection1/. core=collection1
[junit4:junit4]   2> 15094 T130 C12 P56780 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 15116 T95 C13 P56768 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 15123 T95 C13 P56768 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 15128 T95 C13 P56768 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty1\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@198e232; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 15128 T95 C13 P56768 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 15129 T95 C13 P56768 oass.SolrIndexSearcher.<init> Opening Searcher@1b35a10 realtime
[junit4:junit4]   2> 15129 T95 C13 P56768 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15129 T95 C13 P56768 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 26
[junit4:junit4]   2> 15130 T130 C12 P56780 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 15130 T130 C12 P56780 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 15134 T130 C12 P56780 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 15137 T101 C13 P56768 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 15138 T101 C13 P56768 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15145 T130 C12 P56780 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2e77d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 15145 T130 C12 P56780 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 15146 T130 C12 P56780 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 15154 T130 C12 P56780 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2e77d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty3\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2e77d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 15154 T130 C12 P56780 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 15164 T130 C12 P56780 oass.SolrIndexSearcher.<init> Opening Searcher@1673d33 main
[junit4:junit4]   2> 15164 T130 C12 P56780 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15164 T129 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1673d33 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 15165 T130 C12 P56780 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 15165 T130 C12 P56780 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 15165 T130 C12 P56780 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15168 T130 C12 P56780 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 15433 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15434 T91 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:56780_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56780/solr"}
[junit4:junit4]   2> 15451 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15451 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15452 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15452 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 76459 T70 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> 76461 T70 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@15aa2b5
[junit4:junit4]   2> 76461 T70 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> 76461 T70 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 76462 T70 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 76462 T70 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 76464 T70 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 76464 T70 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 76464 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 76466 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 76716 T70 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 76720 T70 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:56796
[junit4:junit4]   2> 76722 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 76722 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999
[junit4:junit4]   2> 76723 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999\'
[junit4:junit4]   2> 76771 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 76773 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 76773 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999
[junit4:junit4]   2> 76773 T70 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999\solr.xml
[junit4:junit4]   2> 76774 T70 oasc.CoreContainer.<init> New CoreContainer 10203054
[junit4:junit4]   2> 76774 T70 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999\'
[junit4:junit4]   2> 76774 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999\'
[junit4:junit4]   2> 76808 T70 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 76834 T70 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56754/solr
[junit4:junit4]   2> 76836 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1d6d136
[junit4:junit4]   2> 76837 T153 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 76837 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 76841 T153 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 76842 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56801
[junit4:junit4]   2> 76842 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56801
[junit4:junit4]   2> 76856 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810007 with negotiated timeout 8000 for client /127.0.0.1:56801
[junit4:junit4]   2> 76856 T153 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810007, negotiated timeout = 8000
[junit4:junit4]   2> 76856 T154 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d6d136 name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 76857 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 76858 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 76860 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 76862 T70 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56796_solr
[junit4:junit4]   2> 76863 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56796_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56796_solr
[junit4:junit4]   2> 76865 T70 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56796_solr
[junit4:junit4]   2> 76870 T90 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 76870 T128 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 76871 T142 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 76871 T104 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 76871 T116 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 76872 T70 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 76872 T70 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 76872 T70 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 76873 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 76873 T70 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 76873 T70 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 76875 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 76875 T70 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 76876 T70 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 76876 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 76891 T70 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 76900 T70 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999\collection1
[junit4:junit4]   2> 76900 T70 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 76904 T70 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 76904 T70 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 76906 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999\collection1\'
[junit4:junit4]   2> 76960 T70 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 77046 T70 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 77050 T70 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 77058 T70 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 77832 T70 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 77840 T70 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 77842 T70 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 77842 T70 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 77843 T70 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty5-1352155177999\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty5\
[junit4:junit4]   2> 77843 T70 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 77845 T70 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty5\index/
[junit4:junit4]   2> 77846 T70 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty5\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 77846 T70 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty5\index forceNew:false
[junit4:junit4]   2> 77858 T70 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty5\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2223ac; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 77860 T70 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 77862 T70 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 77862 T70 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 77863 T70 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 77863 T70 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 77863 T70 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 77865 T70 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 77875 T70 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 77886 T70 oass.SolrIndexSearcher.<init> Opening Searcher@6f9610 main
[junit4:junit4]   2> 77889 T70 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 77889 T70 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 77892 T155 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6f9610 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 77892 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 78377 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 78379 T91 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:56796_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56796/solr"}
[junit4:junit4]   2> 78401 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 78401 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 78403 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 78405 T154 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 78405 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 78533 T70 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 78534 T70 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56796/solr shardId:shard1
[junit4:junit4]   2> 78552 T70 oasc.ZkController.register We are http://127.0.0.1:56796/solr/collection1/ and leader is http://127.0.0.1:56768/solr/collection1/
[junit4:junit4]   2> 78553 T70 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56796/solr
[junit4:junit4]   2> 78555 T70 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 78556 T70 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C14 name=collection1 org.apache.solr.core.SolrCore@17adefb url=http://127.0.0.1:56796/solr/collection1 node=127.0.0.1:56796_solr
[junit4:junit4]   2> 78559 T156 C14 P56796 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 78563 T156 C14 P56796 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 78564 T156 C14 P56796 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 78564 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 78572 T156 C14 P56796 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:56768/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 78573 T156 C14 P56796 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:56796/solr START replicas=[http://127.0.0.1:56768/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 78573 T70 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 78574 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 78574 T156 C14 P56796 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 78576 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 78580 T97 C13 P56768 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 78578 T156 C14 P56796 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 78583 T156 C14 P56796 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 78584 T156 C14 P56796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 78827 T70 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 78832 T70 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:56803
[junit4:junit4]   2> 78834 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 78834 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125
[junit4:junit4]   2> 78835 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125\'
[junit4:junit4]   2> 78868 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 78868 T70 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 78870 T70 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125
[junit4:junit4]   2> 78870 T70 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125\solr.xml
[junit4:junit4]   2> 78870 T70 oasc.CoreContainer.<init> New CoreContainer 20765372
[junit4:junit4]   2> 78870 T70 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125\'
[junit4:junit4]   2> 78870 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125\'
[junit4:junit4]   2> 78892 T70 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 78904 T70 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56754/solr
[junit4:junit4]   2> 78904 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@f73f39
[junit4:junit4]   2> 78908 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 78908 T167 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 78912 T167 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 78912 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56808
[junit4:junit4]   2> 78913 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56808
[junit4:junit4]   2> 78920 T167 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810008, negotiated timeout = 8000
[junit4:junit4]   2> 78920 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810008 with negotiated timeout 8000 for client /127.0.0.1:56808
[junit4:junit4]   2> 78920 T168 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f73f39 name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 78920 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 78921 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810008 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 78923 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810008 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 78926 T70 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56803_solr
[junit4:junit4]   2> 78927 T77 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ad2b97a810008 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56803_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56803_solr
[junit4:junit4]   2> 78928 T70 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56803_solr
[junit4:junit4]   2> 78933 T128 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 78933 T116 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 78936 T90 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 78936 T142 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 78936 T154 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 78936 T104 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 78938 T70 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 78938 T70 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 78938 T70 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 78939 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 78939 T70 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 78941 T70 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 78941 T70 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 78941 T70 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 78942 T70 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 78942 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 78955 T70 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 78963 T70 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125\collection1
[junit4:junit4]   2> 78963 T70 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 78964 T70 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 78964 T70 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 78967 T70 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125\collection1\'
[junit4:junit4]   2> 79010 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 79011 T91 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:56796_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56796/solr"}
[junit4:junit4]   2> 79018 T70 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 79020 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 79020 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 79020 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 79021 T168 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 79021 T154 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 79021 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 79119 T70 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 79121 T70 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 79127 T70 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 79912 T70 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 79919 T70 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 79923 T70 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 79925 T70 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 79926 T70 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-jetty6-1352155180125\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty6\
[junit4:junit4]   2> 79926 T70 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 79927 T70 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536/jetty6\index/
[junit4:junit4]   2> 79928 T70 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty6\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 79929 T70 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty6\index forceNew:false
[junit4:junit4]   2> 79940 T70 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty6\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ccc9b5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 79942 T70 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 79944 T70 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 79945 T70 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 79945 T70 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 79946 T70 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 79946 T70 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 79946 T70 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 79955 T70 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 79967 T70 oass.SolrIndexSearcher.<init> Opening Searcher@19991d0 main
[junit4:junit4]   2> 79969 T70 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 79970 T70 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 79972 T169 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19991d0 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 79972 T70 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 80198 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 80199 T91 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:56803_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56803/solr"}
[junit4:junit4]   2> 80208 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80208 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80208 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80209 T168 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80209 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80209 T154 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80557 T70 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 80557 T70 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56803/solr shardId:shard2
[junit4:junit4]   2> 80575 T70 oasc.ZkController.register We are http://127.0.0.1:56803/solr/collection1/ and leader is http://127.0.0.1:56774/solr/collection1/
[junit4:junit4]   2> 80575 T70 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56803/solr
[junit4:junit4]   2> 80576 T70 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 80576 T70 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C15 name=collection1 org.apache.solr.core.SolrCore@79c67f url=http://127.0.0.1:56803/solr/collection1 node=127.0.0.1:56803_solr
[junit4:junit4]   2> 80578 T170 C15 P56803 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 80579 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 80583 T170 C15 P56803 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 80584 T170 C15 P56803 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 80585 T70 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 80585 T70 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 80586 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 80598 T70 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56754/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c8399b
[junit4:junit4]   2> 80600 T171 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56754
[junit4:junit4]   2> 80600 T70 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 80601 T171 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:56754, initiating session
[junit4:junit4]   2> 80602 T72 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56811
[junit4:junit4]   2> 80602 T72 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56811
[junit4:junit4]   2> 80619 T170 C15 P56803 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:56774/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 80619 T170 C15 P56803 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:56803/solr START replicas=[http://127.0.0.1:56774/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 80620 T170 C15 P56803 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 80620 T170 C15 P56803 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 80625 T170 C15 P56803 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 80625 T170 C15 P56803 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C16 name=collection1 org.apache.solr.core.SolrCore@1f718f2 url=http://127.0.0.1:56774/solr/collection1 node=127.0.0.1:56774_solr
[junit4:junit4]   2> 80633 T109 C16 P56774 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 80643 T75 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ad2b97a810009 with negotiated timeout 10000 for client /127.0.0.1:56811
[junit4:junit4]   2> 80643 T171 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:56754, sessionid = 0x13ad2b97a810009, negotiated timeout = 10000
[junit4:junit4]   2> 80643 T172 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c8399b name:ZooKeeperConnection Watcher:127.0.0.1:56754/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 80643 T70 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 80644 T70 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 80649 T70 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 80649 T70 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 80654 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 80657 T70 oasc.AbstractFullDistribZkTestBase.waitForThingsToLevelOut Wait for recoveries to finish - wait 30 for each attempt
[junit4:junit4]   2> 80657 T70 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):30
[junit4:junit4]   2> 80658 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 80799 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 80800 T91 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:56803_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56803/solr"}
[junit4:junit4]   2> 80809 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80809 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80809 T172 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80809 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80810 T154 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80810 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80809 T168 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 81821 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C14_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:56796_solr, base_url=http://127.0.0.1:56796/solr}
[junit4:junit4]   2> 82089 T156 C14 P56796 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 82089 T156 C14 P56796 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 82089 T156 C14 P56796 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:56768/solr/collection1/. core=collection1
[junit4:junit4]   2> 82089 T156 C14 P56796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 82097 T101 C13 P56768 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 82099 T101 C13 P56768 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 82099 T101 C13 P56768 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 3
[junit4:junit4]   2> 82099 T156 C14 P56796 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 82099 T156 C14 P56796 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 82104 T95 C13 P56768 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 82109 T156 C14 P56796 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty5\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2223ac; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 82111 T156 C14 P56796 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 82111 T156 C14 P56796 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 82121 T156 C14 P56796 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty5\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2223ac; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty5\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2223ac; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 82121 T156 C14 P56796 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 82126 T156 C14 P56796 oass.SolrIndexSearcher.<init> Opening Searcher@acbf5a main
[junit4:junit4]   2> 82128 T156 C14 P56796 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 82128 T155 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@acbf5a main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 82128 T156 C14 P56796 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 82128 T156 C14 P56796 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 82128 T156 C14 P56796 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 82133 T156 C14 P56796 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 82568 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 82569 T91 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:56796_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56796/solr"}
[junit4:junit4]   2> 82585 T172 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 82586 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 82586 T154 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 82586 T168 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 82587 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 82587 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 82587 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 82986 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C15_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:56803_solr, base_url=http://127.0.0.1:56803/solr}
[junit4:junit4]   2> 84122 T170 C15 P56803 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 84122 T170 C15 P56803 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 84122 T170 C15 P56803 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:56774/solr/collection1/. core=collection1
[junit4:junit4]   2> 84122 T170 C15 P56803 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 84133 T113 C16 P56774 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 84140 T113 C16 P56774 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty2\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ade3c6; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 84140 T113 C16 P56774 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 84141 T113 C16 P56774 oass.SolrIndexSearcher.<init> Opening Searcher@1731f3e realtime
[junit4:junit4]   2> 84141 T113 C16 P56774 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 84142 T113 C16 P56774 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 9
[junit4:junit4]   2> 84143 T170 C15 P56803 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 84143 T170 C15 P56803 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 84144 T109 C16 P56774 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 84144 T109 C16 P56774 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 84152 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 84152 T170 C15 P56803 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty6\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ccc9b5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 84154 T170 C15 P56803 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 84154 T170 C15 P56803 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 84162 T170 C15 P56803 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty6\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ccc9b5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1352155101536\jetty6\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ccc9b5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 84163 T170 C15 P56803 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 84167 T170 C15 P56803 oass.SolrIndexSearcher.<init> Opening Searcher@15e004c main
[junit4:junit4]   2> 84168 T170 C15 P56803 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 84168 T169 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15e004c main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 84168 T170 C15 P56803 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 84168 T170 C15 P56803 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 84168 T170 C15 P56803 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 84172 T170 C15 P56803 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 84351 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 84352 T91 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:56803_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56803/solr"}
[junit4:junit4]   2> 84362 T128 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 84364 T90 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 84364 T116 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 84364 T168 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 84364 T154 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 84364 T104 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 84362 T172 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 85316 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 86480 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 87647 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 88814 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 89980 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 91039 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 92088 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 93136 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 94185 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 95233 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 96280 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 97328 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 98374 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 99422 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 100470 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 101422 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 102365 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 103307 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 104250 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 105193 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 106136 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 107079 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 108022 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 108965 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 109908 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 110761 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 111610 T70 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp4479820-162,5,TGRP-CloudSolrServerTest]
[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-CloudSolrServerTest.testDistribSearch-seed#[1FE83E3422B02A8F]-SendThread(127.0.0.1:56754),5,TGRP-CloudSolrServerTest]
[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[qtp24132248-151 Acceptor0 SelectChannelConnector@0.0.0.0:56796,5,TGRP-CloudSolrServerTest]
[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[searcherExecutor-141-thread-1,5,TGRP-CloudSolrServerTest]
[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[qtp4479820-161,5,TGRP-CloudSolrServerTest]
[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-CloudSolrServerTest.testDistribSearch-seed#[1FE83E3422B02A8F]-SendThread(127.0.0.1:56754),5,TGRP-CloudSolrServerTest]
[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[Overseer-88614836753203202-127.0.0.1:56762_solr-n_0000000000,5,Overseer collection creation process.]
[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.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:811)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:969)
[junit4:junit4]   1> java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1281)
[junit4:junit4]   1> java.util.concurrent.CountDownLatch.await(CountDownLatch.java:207)
[junit4:junit4]   1> org.apache.solr.cloud.DistributedQueue$LatchChildWatcher.await(DistributedQueue.java:179)
[junit4:junit4]   1> org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:277)
[junit4:junit4]   1> org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:86)
[junit4:junit4]   1> java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> 
[junit4:junit4]   1> Thread[qtp29681093-124,5,TGRP-CloudSolrServerTest]
[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-CloudSolrServerTest.testDistribSearch-seed#[1FE83E3422B02A8F]-EventThread,5,TGRP-CloudSolrServerTest]
[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[qtp9718129-96 Selector0,5,TGRP-CloudSolrServerTest]
[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[TEST-CloudSolrServerTest.testDistribSearch-seed#[1FE83E3422B02A8F]-SendThread(127.0.0.1:56754),5,TGRP-CloudSolrServerTest]
[junit4:junit4]   1> sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Nati

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

            "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:56774_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:56774/solr",
[junit4:junit4]   1>                 "leader":"true"},
[junit4:junit4]   1>               "127.0.0.1:56788_solr_collection1":{
[junit4:junit4]   1>                 "shard":null,
[junit4:junit4]   1>                 "roles":null,
[junit4:junit4]   1>                 "state":"down",
[junit4:junit4]   1>                 "core":"collection1",
[junit4:junit4]   1>                 "collection":"collection1",
[junit4:junit4]   1>                 "node_name":"127.0.0.1:56788_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:56788/solr"},
[junit4:junit4]   1>               "127.0.0.1:56803_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:56803_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:56803/solr"}}}},
[junit4:junit4]   1>         "control_collection":{"control_shard":{"replicas":{"127.0.0.1:56762_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:56762_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:56762/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=CloudSolrServerTest -Dtests.method=testDistribSearch -Dtests.seed=1FE83E3422B02A8F -Dtests.slow=true -Dtests.locale=ja_JP -Dtests.timezone=America/Managua -Dtests.file.encoding=Cp1252
[junit4:junit4] FAILURE  120s | CloudSolrServerTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 30 seconds
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([1FE83E3422B02A8F:9E0EB02C55EF4AB3]: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.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:558)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1133)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrServerTest.doTest(CloudSolrServerTest.java:95)
[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> 120291 T70 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=DefaultSimilarity, locale=ja_JP, timezone=America/Managua
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_37 (32-bit)/cpus=2,threads=1,free=5931080,total=19279872
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TermsResponseTest, BasicHttpSolrServerTest, MultiCoreExampleJettyTest, SolrExampleEmbeddedTest, MergeIndexesEmbeddedTest, CloudSolrServerTest]
[junit4:junit4] Completed in 120.33s, 1 test, 1 failure <<< FAILURES!

[...truncated 120 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:185: 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: 43 suites, 239 tests, 1 failure

Total time: 47 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.6.0_37 -client -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message