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-trunk-Windows (32bit/jdk1.6.0_37) - Build # 1409 - Still Failing!
Date Fri, 02 Nov 2012 11:19:21 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows/1409/
Java: 32bit/jdk1.6.0_37 -client -XX:+UseParallelGC

1 tests failed.
FAILED:  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([A8D5E3DD16097CCB:29336DC561561CF7]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:166)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:559)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1134)
	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 8684 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest
[junit4:junit4]   2> 164 T14 oasc.AbstractZkTestCase.<clinit> WARNING TEST_HOME() does not exist - solrj test?
[junit4:junit4]   2> 171 T14 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-CloudSolrServerTest-1351854890745
[junit4:junit4]   2> 181 T14 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 291 T15 oaz.Environment.logEnv Server environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 291 T15 oaz.Environment.logEnv Server environment:host.name=JenkinsSlave-PC
[junit4:junit4]   2> 293 T15 oaz.Environment.logEnv Server environment:java.version=1.6.0_37
[junit4:junit4]   2> 293 T15 oaz.Environment.logEnv Server environment:java.vendor=Sun Microsystems Inc.
[junit4:junit4]   2> 293 T15 oaz.Environment.logEnv Server environment:java.home=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\jre
[junit4:junit4]   2> 294 T15 oaz.Environment.logEnv Server environment:java.class.path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\classes\test;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\codecs\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\commons-io-2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpclient-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpcore-4.1.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpmime-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\jcl-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\log4j-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\slf4j-api-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\slf4j-jdk14-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\wstx-asl-3.2.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\zookeeper-3.3.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\test-framework\lib\junit-4.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\test-framework\lib\randomizedtesting-runner-2.0.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\common\lucene-analyzers-common-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\kuromoji\lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\phonetic\lucene-analyzers-phonetic-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\highlighter\lucene-highlighter-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\memory\lucene-memory-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\misc\lucene-misc-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\spatial\lucene-spatial-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\suggest\lucene-suggest-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\grouping\lucene-grouping-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\queries\lucene-queries-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\queryparser\lucene-queryparser-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-cli-1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-codec-1.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-fileupload-1.2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-lang-2.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\easymock-2.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\guava-r05.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\javax.servlet-api-3.0.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\metrics-core-2.1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\spatial4j-0.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-continuation-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-deploy-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-http-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-io-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-jmx-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-security-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-server-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-servlet-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-util-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-webapp-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-xml-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\servlet-api-3.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\example-DIH\solr\db\lib\hsqldb-1.8.0.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-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> 295 T15 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> 296 T15 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 296 T15 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4]   2> 296 T15 oaz.Environment.logEnv Server environment:os.name=Windows 7
[junit4:junit4]   2> 296 T15 oaz.Environment.logEnv Server environment:os.arch=x86
[junit4:junit4]   2> 298 T15 oaz.Environment.logEnv Server environment:os.version=6.1
[junit4:junit4]   2> 298 T15 oaz.Environment.logEnv Server environment:user.name=JenkinsSlave
[junit4:junit4]   2> 298 T15 oaz.Environment.logEnv Server environment:user.home=C:\Users\JenkinsSlave
[junit4:junit4]   2> 298 T15 oaz.Environment.logEnv Server environment:user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 327 T15 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 347 T15 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 403 T15 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 442 T14 oasc.ZkTestServer.run start zk server on port:51254
[junit4:junit4]   2> 574 T14 oaz.Environment.logEnv Client environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 576 T14 oaz.Environment.logEnv Client environment:host.name=JenkinsSlave-PC
[junit4:junit4]   2> 577 T14 oaz.Environment.logEnv Client environment:java.version=1.6.0_37
[junit4:junit4]   2> 577 T14 oaz.Environment.logEnv Client environment:java.vendor=Sun Microsystems Inc.
[junit4:junit4]   2> 577 T14 oaz.Environment.logEnv Client environment:java.home=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.6.0_37\jre
[junit4:junit4]   2> 578 T14 oaz.Environment.logEnv Client environment:java.class.path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\classes\test;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\codecs\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\commons-io-2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpclient-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpcore-4.1.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpmime-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\jcl-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\log4j-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\slf4j-api-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\slf4j-jdk14-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\wstx-asl-3.2.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\zookeeper-3.3.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\test-framework\lib\junit-4.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\test-framework\lib\randomizedtesting-runner-2.0.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\common\lucene-analyzers-common-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\kuromoji\lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\phonetic\lucene-analyzers-phonetic-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\highlighter\lucene-highlighter-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\memory\lucene-memory-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\misc\lucene-misc-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\spatial\lucene-spatial-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\suggest\lucene-suggest-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\grouping\lucene-grouping-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\queries\lucene-queries-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\queryparser\lucene-queryparser-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-cli-1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-codec-1.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-fileupload-1.2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-lang-2.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\easymock-2.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\guava-r05.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\javax.servlet-api-3.0.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\metrics-core-2.1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\spatial4j-0.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-continuation-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-deploy-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-http-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-io-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-jmx-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-security-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-server-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-servlet-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-util-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-webapp-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-xml-8.1.2.v20120308.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\servlet-api-3.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\example-DIH\solr\db\lib\hsqldb-1.8.0.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-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> 579 T14 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> 581 T14 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 581 T14 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4]   2> 582 T14 oaz.Environment.logEnv Client environment:os.name=Windows 7
[junit4:junit4]   2> 582 T14 oaz.Environment.logEnv Client environment:os.arch=x86
[junit4:junit4]   2> 582 T14 oaz.Environment.logEnv Client environment:os.version=6.1
[junit4:junit4]   2> 582 T14 oaz.Environment.logEnv Client environment:user.name=JenkinsSlave
[junit4:junit4]   2> 583 T14 oaz.Environment.logEnv Client environment:user.home=C:\Users\JenkinsSlave
[junit4:junit4]   2> 583 T14 oaz.Environment.logEnv Client environment:user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 588 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@15d601f
[junit4:junit4]   2> 641 T20 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 641 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 646 T20 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 647 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51257
[junit4:junit4]   2> 667 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51257
[junit4:junit4]   2> 678 T18 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 724 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90000 with negotiated timeout 10000 for client /127.0.0.1:51257
[junit4:junit4]   2> 724 T20 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90000, negotiated timeout = 10000
[junit4:junit4]   2> 731 T21 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15d601f name:ZooKeeperConnection Watcher:127.0.0.1:51254 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 733 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 736 T14 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 804 T19 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ac0d49ff90000
[junit4:junit4]   2> 808 T14 oaz.ZooKeeper.close Session: 0x13ac0d49ff90000 closed
[junit4:junit4]   2> 809 T16 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x13ac0d49ff90000, likely client has closed socket
[junit4:junit4]   2> 809 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1d709a5
[junit4:junit4]   2> 809 T21 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 812 T16 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51257 which had sessionid 0x13ac0d49ff90000
[junit4:junit4]   2> 814 T22 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 814 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 817 T22 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 818 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51260
[junit4:junit4]   2> 819 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51260
[junit4:junit4]   2> 824 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90001 with negotiated timeout 10000 for client /127.0.0.1:51260
[junit4:junit4]   2> 824 T22 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90001, negotiated timeout = 10000
[junit4:junit4]   2> 825 T23 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d709a5 name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 825 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 848 T14 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 856 T14 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 862 T14 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 869 T14 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 875 T14 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 902 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 913 T14 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 914 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 922 T14 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 923 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 935 T14 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\stopwords.txt because it doesn't exist
[junit4:junit4]   2> 937 T14 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\protwords.txt because it doesn't exist
[junit4:junit4]   2> 937 T14 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\currency.xml because it doesn't exist
[junit4:junit4]   2> 939 T14 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\open-exchange-rates.json because it doesn't exist
[junit4:junit4]   2> 939 T14 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4:junit4]   2> 939 T14 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\old_synonyms.txt because it doesn't exist
[junit4:junit4]   2> 939 T14 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\synonyms.txt because it doesn't exist
[junit4:junit4]   2> 940 T19 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ac0d49ff90001
[junit4:junit4]   2> 944 T14 oaz.ZooKeeper.close Session: 0x13ac0d49ff90001 closed
[junit4:junit4]   2> 945 T16 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51260 which had sessionid 0x13ac0d49ff90001
[junit4:junit4]   2> 945 T23 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1594 T14 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1914 T14 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:51261
[junit4:junit4]   2> 2023 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2023 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516
[junit4:junit4]   2> 2024 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516\'
[junit4:junit4]   2> 2164 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2169 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2170 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516
[junit4:junit4]   2> 2207 T14 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516\solr.xml
[junit4:junit4]   2> 2212 T14 oasc.CoreContainer.<init> New CoreContainer 31992690
[junit4:junit4]   2> 2215 T14 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516\'
[junit4:junit4]   2> 2215 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516\'
[junit4:junit4]   2> 2285 T14 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2332 T14 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51254/solr
[junit4:junit4]   2> 2392 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1956391
[junit4:junit4]   2> 2395 T34 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 2395 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2400 T34 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 2400 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51266
[junit4:junit4]   2> 2401 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51266
[junit4:junit4]   2> 2410 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90002 with negotiated timeout 8000 for client /127.0.0.1:51266
[junit4:junit4]   2> 2410 T34 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90002, negotiated timeout = 8000
[junit4:junit4]   2> 2411 T35 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1956391 name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2411 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2439 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2455 T14 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 2461 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51261_solr
[junit4:junit4]   2> 2467 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51261_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51261_solr
[junit4:junit4]   2> 2468 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51261_solr
[junit4:junit4]   2> 2492 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2493 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2493 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2493 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2494 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2494 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2495 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2495 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2497 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2528 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2923 T14 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 2953 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 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> 2958 T14 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 2963 T14 oasc.Overseer.start Overseer (id=88595162136641538-127.0.0.1:51261_solr-n_0000000000) starting
[junit4:junit4]   2> 2964 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2976 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2979 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2985 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2987 T37 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 2987 T14 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 2994 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3039 T36 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 3254 T14 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516\collection1
[junit4:junit4]   2> 3254 T14 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 3257 T14 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3257 T14 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 3262 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516\collection1\'
[junit4:junit4]   2> 3391 T14 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3580 T14 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3632 T14 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3685 T14 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5624 T14 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5629 T14 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5637 T14 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 5639 T14 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 5816 T14 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351854891516\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/control/data\
[junit4:junit4]   2> 5821 T14 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 5833 T14 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5965 T14 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\index1893918939tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ed081) assuming 'simple'
[junit4:junit4]   2> 5972 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\control\data forceNew:false
[junit4:junit4]   2> 5973 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\control\data
[junit4:junit4]   2> 5973 T14 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/control/data\index/
[junit4:junit4]   2> 5976 T14 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6010 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\control\data\index forceNew:false
[junit4:junit4]   2> 6376 T14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@13e6346 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1cb374f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6377 T14 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6381 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\control\data\index
[junit4:junit4]   2> 7307 T14 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7307 T14 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7313 T14 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7336 T14 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7378 T14 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7382 T14 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7412 T14 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7440 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\control\data
[junit4:junit4]   2> 7496 T14 oass.SolrIndexSearcher.<init> Opening Searcher@28ca1c main
[junit4:junit4]   2> 7601 T14 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7601 T14 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7765 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7765 T41 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28ca1c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7767 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 7780 T14 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 7780 T14 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51261/solr shardId:control_shard
[junit4:junit4]   2> 7793 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 7805 T14 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 7824 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:delete cxid:0x54 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> 7828 T14 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7829 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x55 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7950 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7952 T36 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:51261_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51261/solr"}
[junit4:junit4]   2> 7967 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 8006 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 8531 T14 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 8531 T14 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 8531 T14 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51261/solr/collection1/
[junit4:junit4]   2> 8532 T14 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 8532 T14 oasc.SyncStrategy.syncToMe http://127.0.0.1:51261/solr/collection1/ has no replicas
[junit4:junit4]   2> 8532 T14 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51261/solr/collection1/
[junit4:junit4]   2> 8532 T14 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 8546 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90002 type:create cxid:0x7b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8718 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8731 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 8765 T14 oasc.ZkController.register We are http://127.0.0.1:51261/solr/collection1/ and leader is http://127.0.0.1:51261/solr/collection1/
[junit4:junit4]   2> 8770 T14 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51261/solr
[junit4:junit4]   2> 8770 T14 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8770 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8776 T14 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8777 T14 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 8778 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8826 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8848 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 8864 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c634b9
[junit4:junit4]   2> 8867 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8867 T42 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 8871 T42 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 8871 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51269
[junit4:junit4]   2> 8872 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51269
[junit4:junit4]   2> 8876 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90003 with negotiated timeout 10000 for client /127.0.0.1:51269
[junit4:junit4]   2> 8876 T42 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90003, negotiated timeout = 10000
[junit4:junit4]   2> 8878 T43 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c634b9 name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8878 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8880 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8888 T14 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 9177 T14 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 9179 T14 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:51270
[junit4:junit4]   2> 9181 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 9182 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459
[junit4:junit4]   2> 9182 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459\'
[junit4:junit4]   2> 9228 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 9230 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 9230 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459
[junit4:junit4]   2> 9231 T14 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459\solr.xml
[junit4:junit4]   2> 9231 T14 oasc.CoreContainer.<init> New CoreContainer 9744710
[junit4:junit4]   2> 9232 T14 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459\'
[junit4:junit4]   2> 9232 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459\'
[junit4:junit4]   2> 9268 T14 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 9298 T14 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51254/solr
[junit4:junit4]   2> 9299 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a99836
[junit4:junit4]   2> 9300 T53 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 9300 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 9305 T53 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 9305 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51275
[junit4:junit4]   2> 9306 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51275
[junit4:junit4]   2> 9317 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90004 with negotiated timeout 8000 for client /127.0.0.1:51275
[junit4:junit4]   2> 9317 T53 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90004, negotiated timeout = 8000
[junit4:junit4]   2> 9317 T54 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a99836 name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 9318 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 9319 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9322 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9325 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51270_solr
[junit4:junit4]   2> 9326 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51270_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51270_solr
[junit4:junit4]   2> 9328 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51270_solr
[junit4:junit4]   2> 9334 T43 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 9334 T35 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 9337 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 9337 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 9338 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 9338 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 9340 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 9340 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 9340 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 9343 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 9344 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 9344 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 9344 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 9375 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9390 T14 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459\collection1
[junit4:junit4]   2> 9390 T14 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9393 T14 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9393 T14 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9397 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459\collection1\'
[junit4:junit4]   2> 9439 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9442 T36 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:51261_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51261/solr"}
[junit4:junit4]   2> 9461 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 9462 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 9464 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 9473 T14 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 9578 T14 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9581 T14 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9610 T14 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 10547 T14 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10555 T14 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10560 T14 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 10561 T14 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 10562 T14 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351854899459\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty1\
[junit4:junit4]   2> 10562 T14 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 10564 T14 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 10564 T14 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@161cd25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e35ecd) assuming 'simple'
[junit4:junit4]   2> 10565 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1 forceNew:false
[junit4:junit4]   2> 10565 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1
[junit4:junit4]   2> 10566 T14 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty1\index/
[junit4:junit4]   2> 10568 T14 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 10569 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1\index forceNew:false
[junit4:junit4]   2> 10580 T14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@18ddc48 lockFactory=org.apache.lucene.store.NativeFSLockFactory@19478c7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10580 T14 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10581 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1\index
[junit4:junit4]   2> 10584 T14 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10585 T14 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 10585 T14 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 10587 T14 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10588 T14 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10589 T14 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10600 T14 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10614 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1
[junit4:junit4]   2> 10615 T14 oass.SolrIndexSearcher.<init> Opening Searcher@119b1ca main
[junit4:junit4]   2> 10617 T14 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10619 T14 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10633 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10634 T55 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@119b1ca main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10880 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10883 T36 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:51270_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51270/solr"}
[junit4:junit4]   2> 10883 T36 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 10906 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 10906 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 10906 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 11335 T14 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 11335 T14 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51270/solr shardId:shard1
[junit4:junit4]   2> 11337 T14 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 11359 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90004 type:delete cxid:0x28 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 11362 T14 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 11362 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90004 type:create cxid:0x29 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11369 T14 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 11369 T14 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 11370 T14 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51270/solr/collection1/
[junit4:junit4]   2> 11370 T14 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 11371 T14 oasc.SyncStrategy.syncToMe http://127.0.0.1:51270/solr/collection1/ has no replicas
[junit4:junit4]   2> 11371 T14 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51270/solr/collection1/
[junit4:junit4]   2> 11374 T14 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 11386 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90004 type:create cxid:0x33 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11612 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11634 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 11634 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 11634 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 11673 T14 oasc.ZkController.register We are http://127.0.0.1:51270/solr/collection1/ and leader is http://127.0.0.1:51270/solr/collection1/
[junit4:junit4]   2> 11673 T14 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51270/solr
[junit4:junit4]   2> 11673 T14 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 11674 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11679 T14 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11680 T14 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 11681 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 11681 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 11940 T14 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 11944 T14 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:51276
[junit4:junit4]   2> 11945 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 11945 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262
[junit4:junit4]   2> 11946 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262\'
[junit4:junit4]   2> 11999 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 12001 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12001 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262
[junit4:junit4]   2> 12001 T14 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262\solr.xml
[junit4:junit4]   2> 12002 T14 oasc.CoreContainer.<init> New CoreContainer 12505597
[junit4:junit4]   2> 12002 T14 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262\'
[junit4:junit4]   2> 12004 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262\'
[junit4:junit4]   2> 12043 T14 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 12076 T14 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51254/solr
[junit4:junit4]   2> 12076 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@17892d5
[junit4:junit4]   2> 12078 T65 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 12078 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12081 T65 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 12081 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51281
[junit4:junit4]   2> 12084 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51281
[junit4:junit4]   2> 12091 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90005 with negotiated timeout 8000 for client /127.0.0.1:51281
[junit4:junit4]   2> 12091 T65 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90005, negotiated timeout = 8000
[junit4:junit4]   2> 12092 T66 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17892d5 name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12093 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12095 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12097 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12101 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51276_solr
[junit4:junit4]   2> 12103 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51276_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51276_solr
[junit4:junit4]   2> 12105 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51276_solr
[junit4:junit4]   2> 12110 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 12112 T54 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 12112 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 12114 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 12114 T35 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 12114 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12114 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 12114 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 12115 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12115 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12116 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12116 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12118 T43 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 12118 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12118 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12120 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 12141 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 12154 T14 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262\collection1
[junit4:junit4]   2> 12154 T14 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 12156 T14 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 12157 T14 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 12160 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262\collection1\'
[junit4:junit4]   2> 12218 T14 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 12289 T14 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 12290 T14 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 12300 T14 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 12345 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12348 T36 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:51270_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51270/solr"}
[junit4:junit4]   2> 12369 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 12371 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 12372 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 12375 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 13128 T14 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 13132 T14 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 13136 T14 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 13137 T14 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 13137 T14 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351854902262\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty2\
[junit4:junit4]   2> 13139 T14 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 13139 T14 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 13140 T14 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@85e57 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@169a1c5) assuming 'simple'
[junit4:junit4]   2> 13140 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty2 forceNew:false
[junit4:junit4]   2> 13143 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty2
[junit4:junit4]   2> 13143 T14 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty2\index/
[junit4:junit4]   2> 13144 T14 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 13146 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty2\index forceNew:false
[junit4:junit4]   2> 13150 T14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15264db lockFactory=org.apache.lucene.store.NativeFSLockFactory@eb9f0d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13150 T14 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 13151 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty2\index
[junit4:junit4]   2> 13152 T14 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 13152 T14 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 13155 T14 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 13155 T14 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 13157 T14 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 13157 T14 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 13165 T14 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 13171 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty2
[junit4:junit4]   2> 13173 T14 oass.SolrIndexSearcher.<init> Opening Searcher@190725e main
[junit4:junit4]   2> 13176 T14 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 13176 T14 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 13186 T67 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@190725e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 13186 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 13796 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13797 T36 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:51276_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51276/solr"}
[junit4:junit4]   2> 13808 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 13808 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 13809 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 13809 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 13893 T14 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 13893 T14 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51276/solr shardId:shard2
[junit4:junit4]   2> 13895 T14 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 13908 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90005 type:delete cxid:0x27 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> 13911 T14 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 13913 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90005 type:create cxid:0x28 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 13919 T14 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 13921 T14 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 13921 T14 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51276/solr/collection1/
[junit4:junit4]   2> 13922 T14 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 13923 T14 oasc.SyncStrategy.syncToMe http://127.0.0.1:51276/solr/collection1/ has no replicas
[junit4:junit4]   2> 13923 T14 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51276/solr/collection1/
[junit4:junit4]   2> 13923 T14 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 13934 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90005 type:create cxid:0x31 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14515 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14534 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 14534 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 14534 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 14534 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 14576 T14 oasc.ZkController.register We are http://127.0.0.1:51276/solr/collection1/ and leader is http://127.0.0.1:51276/solr/collection1/
[junit4:junit4]   2> 14576 T14 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51276/solr
[junit4:junit4]   2> 14576 T14 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 14577 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 14583 T14 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14586 T14 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 14586 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 14587 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 14843 T14 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 14847 T14 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:51282
[junit4:junit4]   2> 14848 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 14849 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166
[junit4:junit4]   2> 14849 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166\'
[junit4:junit4]   2> 14890 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 14890 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 14892 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166
[junit4:junit4]   2> 14892 T14 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166\solr.xml
[junit4:junit4]   2> 14892 T14 oasc.CoreContainer.<init> New CoreContainer 24134853
[junit4:junit4]   2> 14892 T14 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166\'
[junit4:junit4]   2> 14893 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166\'
[junit4:junit4]   2> 14921 T14 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 14943 T14 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51254/solr
[junit4:junit4]   2> 14945 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1aa8241
[junit4:junit4]   2> 14947 T77 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 14947 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 14950 T77 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 14951 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51287
[junit4:junit4]   2> 14951 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51287
[junit4:junit4]   2> 14962 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90006 with negotiated timeout 8000 for client /127.0.0.1:51287
[junit4:junit4]   2> 14962 T77 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90006, negotiated timeout = 8000
[junit4:junit4]   2> 14962 T78 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1aa8241 name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 14964 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 14965 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14969 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14974 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51282_solr
[junit4:junit4]   2> 14976 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51282_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51282_solr
[junit4:junit4]   2> 14977 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51282_solr
[junit4:junit4]   2> 14983 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 14984 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 14987 T54 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14987 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 14988 T35 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14988 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 14993 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 14995 T43 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14993 T66 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14995 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 14995 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 14996 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 14996 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 14999 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 14999 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 14999 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 15000 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 15000 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 15019 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 15032 T14 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166\collection1
[junit4:junit4]   2> 15032 T14 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 15033 T14 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 15033 T14 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 15037 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166\collection1\'
[junit4:junit4]   2> 15100 T14 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 15216 T14 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 15219 T14 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 15236 T14 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 15245 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15246 T36 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:51276_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51276/solr"}
[junit4:junit4]   2> 15264 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 15267 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 15268 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 15268 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 15270 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 16118 T14 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 16125 T14 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 16129 T14 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 16129 T14 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 16130 T14 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351854905166\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty3\
[junit4:junit4]   2> 16132 T14 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 16132 T14 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 16133 T14 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@153e0c0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c88f2b) assuming 'simple'
[junit4:junit4]   2> 16133 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3 forceNew:false
[junit4:junit4]   2> 16133 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3
[junit4:junit4]   2> 16134 T14 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty3\index/
[junit4:junit4]   2> 16136 T14 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 16137 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3\index forceNew:false
[junit4:junit4]   2> 16144 T14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@af8b32 lockFactory=org.apache.lucene.store.NativeFSLockFactory@baa573),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 16144 T14 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 16145 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3\index
[junit4:junit4]   2> 16148 T14 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 16148 T14 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 16149 T14 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 16151 T14 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 16152 T14 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 16153 T14 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 16164 T14 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 16175 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3
[junit4:junit4]   2> 16175 T14 oass.SolrIndexSearcher.<init> Opening Searcher@16acad2 main
[junit4:junit4]   2> 16178 T14 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 16179 T14 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 16190 T79 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16acad2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 16190 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16707 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16708 T36 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:51282_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51282/solr"}
[junit4:junit4]   2> 16719 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 16719 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 16719 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 16720 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 16719 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 16892 T14 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 16892 T14 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51282/solr shardId:shard1
[junit4:junit4]   2> 16900 T14 oasc.ZkController.register We are http://127.0.0.1:51282/solr/collection1/ and leader is http://127.0.0.1:51270/solr/collection1/
[junit4:junit4]   2> 16900 T14 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51282/solr
[junit4:junit4]   2> 16900 T14 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 16901 T14 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> 16915 T14 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16919 T14 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 16920 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 16920 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@e64795 url=http://127.0.0.1:51282/solr/collection1 node=127.0.0.1:51282_solr
[junit4:junit4]   2> 16938 T80 C0 P51282 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 16945 T80 C0 P51282 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 16945 T80 C0 P51282 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16950 T80 C0 P51282 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:51270/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 16969 T80 C0 P51282 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 17004 T80 C0 P51282 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:51282/solr START replicas=[http://127.0.0.1:51270/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 17029 T80 C0 P51282 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 17033 T80 C0 P51282 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 17033 T80 C0 P51282 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 17034 T80 C0 P51282 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 17269 T14 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 17290 T14 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:51288
[junit4:junit4]   2> 17291 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 17294 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517
[junit4:junit4]   2> 17294 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517\'
[junit4:junit4]   2> 17392 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17393 T36 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:51282_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51282/solr"}
[junit4:junit4]   2> 17417 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 17421 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 17421 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 17422 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 17425 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 17453 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 17453 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 17454 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517
[junit4:junit4]   2> 17454 T14 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517\solr.xml
[junit4:junit4]   2> 17455 T14 oasc.CoreContainer.<init> New CoreContainer 14837237
[junit4:junit4]   2> 17456 T14 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517\'
[junit4:junit4]   2> 17456 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517\'
[junit4:junit4]   2> 17902 T14 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 17935 T14 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51254/solr
[junit4:junit4]   2> 17936 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@ff6348
[junit4:junit4]   2> 17939 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 17940 T93 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 17943 T93 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 17943 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51295
[junit4:junit4]   2> 17945 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51295
[junit4:junit4]   2> 17951 T93 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90007, negotiated timeout = 8000
[junit4:junit4]   2> 17951 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90007 with negotiated timeout 8000 for client /127.0.0.1:51295
[junit4:junit4]   2> 17953 T94 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ff6348 name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 17953 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 17954 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17958 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17962 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51288_solr
[junit4:junit4]   2> 17962 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51288_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51288_solr
[junit4:junit4]   2> 17964 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51288_solr
[junit4:junit4]   2> 17972 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 17972 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 17973 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 17975 T54 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17975 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 17977 T35 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17977 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 17978 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 17980 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 17981 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 17981 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 17983 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 17983 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 17984 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 17984 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 17985 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 17985 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 18005 T66 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 18008 T78 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 18010 T43 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 18027 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 18044 T14 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517\collection1
[junit4:junit4]   2> 18046 T14 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 18052 T14 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 18052 T14 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 18057 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517\collection1\'
[junit4:junit4]   2> ASYNC  NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@1a3f178 url=http://127.0.0.1:51270/solr/collection1 node=127.0.0.1:51270_solr
[junit4:junit4]   2> 18268 T48 C1 P51270 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=70 
[junit4:junit4]   2> 18301 T14 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 18413 T14 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 18415 T14 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 18432 T14 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 19302 T14 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 19308 T14 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 19310 T14 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 19312 T14 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 19314 T14 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351854907517\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty4\
[junit4:junit4]   2> 19314 T14 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 19315 T14 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 19315 T14 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@a3ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39b99d) assuming 'simple'
[junit4:junit4]   2> 19316 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4 forceNew:false
[junit4:junit4]   2> 19316 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4
[junit4:junit4]   2> 19318 T14 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty4\index/
[junit4:junit4]   2> 19319 T14 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 19320 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4\index forceNew:false
[junit4:junit4]   2> 19329 T14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1930c3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ac5f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19329 T14 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19331 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4\index
[junit4:junit4]   2> 19332 T14 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 19332 T14 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 19332 T14 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 19334 T14 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 19335 T14 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 19335 T14 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 19343 T14 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 19352 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4
[junit4:junit4]   2> 19354 T14 oass.SolrIndexSearcher.<init> Opening Searcher@191394e main
[junit4:junit4]   2> 19356 T14 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 19357 T14 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 19365 T95 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@191394e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 19365 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 19955 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19956 T36 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:51288_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51288/solr"}
[junit4:junit4]   2> 19986 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 19986 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 19986 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 19986 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 19986 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 19986 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 20001 T14 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 20001 T14 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51288/solr shardId:shard2
[junit4:junit4]   2> 20005 T14 oasc.ZkController.register We are http://127.0.0.1:51288/solr/collection1/ and leader is http://127.0.0.1:51276/solr/collection1/
[junit4:junit4]   2> 20005 T14 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51288/solr
[junit4:junit4]   2> 20007 T14 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 20007 T14 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@1c2e163 url=http://127.0.0.1:51288/solr/collection1 node=127.0.0.1:51288_solr
[junit4:junit4]   2> 20008 T96 C2 P51288 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 20009 T96 C2 P51288 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 20009 T96 C2 P51288 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 20010 T14 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20013 T96 C2 P51288 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:51276/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 20013 T96 C2 P51288 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:51288/solr START replicas=[http://127.0.0.1:51276/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 20013 T14 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 20013 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 20014 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 20013 T96 C2 P51288 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 20019 T96 C2 P51288 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 20019 T96 C2 P51288 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 20019 T96 C2 P51288 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@8bd3bb url=http://127.0.0.1:51276/solr/collection1 node=127.0.0.1:51276_solr
[junit4:junit4]   2> 20027 T62 C3 P51276 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 20276 T14 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 20281 T14 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:51298
[junit4:junit4]   2> 20282 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 20282 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603
[junit4:junit4]   2> 20283 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603\'
[junit4:junit4]   2> 20322 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 20322 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 20322 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603
[junit4:junit4]   2> 20325 T14 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603\solr.xml
[junit4:junit4]   2> 20325 T14 oasc.CoreContainer.<init> New CoreContainer 11667177
[junit4:junit4]   2> 20326 T14 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603\'
[junit4:junit4]   2> 20326 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603\'
[junit4:junit4]   2> 20354 T14 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 20377 T14 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51254/solr
[junit4:junit4]   2> 20379 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f81efb
[junit4:junit4]   2> 20380 T107 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 20380 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 20384 T107 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 20384 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51303
[junit4:junit4]   2> 20384 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51303
[junit4:junit4]   2> 20392 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90008 with negotiated timeout 8000 for client /127.0.0.1:51303
[junit4:junit4]   2> 20392 T107 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90008, negotiated timeout = 8000
[junit4:junit4]   2> 20393 T108 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f81efb name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 20393 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 20395 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90008 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 20397 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90008 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 20399 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51298_solr
[junit4:junit4]   2> 20399 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90008 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51298_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51298_solr
[junit4:junit4]   2> 20402 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51298_solr
[junit4:junit4]   2> 20406 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 20406 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 20407 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 20407 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 20409 T54 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 20409 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 20410 T35 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 20410 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 20413 T43 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 20413 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 20414 T78 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 20414 T94 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 20414 T66 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 20414 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 20415 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 20417 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 20417 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 20417 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 20418 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 20418 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 20418 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 20419 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 20435 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 20446 T14 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603\collection1
[junit4:junit4]   2> 20447 T14 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 20447 T14 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 20448 T14 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 20451 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603\collection1\'
[junit4:junit4]   2> 20513 T14 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 20600 T14 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 20610 T14 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 20620 T14 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 20644 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20645 T36 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:51288_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51288/solr"}
[junit4:junit4]   2> 20663 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 20664 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 20664 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 20664 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 20666 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 20666 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 20666 T108 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2>  C0_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:51282_solr, base_url=http://127.0.0.1:51282/solr}
[junit4:junit4]   2> 20986 T80 C0 P51282 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 20987 T80 C0 P51282 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 20987 T80 C0 P51282 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:51270/solr/collection1/. core=collection1
[junit4:junit4]   2> 20987 T80 C0 P51282 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 21020 T49 C1 P51270 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 21047 T49 C1 P51270 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 21047 T49 C1 P51270 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1
[junit4:junit4]   2> 21055 T49 C1 P51270 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@18ddc48 lockFactory=org.apache.lucene.store.NativeFSLockFactory@19478c7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 21057 T49 C1 P51270 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 21058 T49 C1 P51270 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1
[junit4:junit4]   2> 21058 T49 C1 P51270 oass.SolrIndexSearcher.<init> Opening Searcher@1cfd7c realtime
[junit4:junit4]   2> 21059 T49 C1 P51270 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 21059 T49 C1 P51270 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 59
[junit4:junit4]   2> 21157 T80 C0 P51282 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 21157 T80 C0 P51282 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 21172 T80 C0 P51282 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 21239 T46 C1 P51270 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 21241 T46 C1 P51270 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=65 
[junit4:junit4]   2> 21269 T80 C0 P51282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3
[junit4:junit4]   2> 21274 T80 C0 P51282 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@af8b32 lockFactory=org.apache.lucene.store.NativeFSLockFactory@baa573),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 21276 T80 C0 P51282 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 21276 T80 C0 P51282 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 21276 T80 C0 P51282 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@af8b32 lockFactory=org.apache.lucene.store.NativeFSLockFactory@baa573),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@af8b32 lockFactory=org.apache.lucene.store.NativeFSLockFactory@baa573),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 21278 T80 C0 P51282 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 21278 T80 C0 P51282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3
[junit4:junit4]   2> 21283 T80 C0 P51282 oass.SolrIndexSearcher.<init> Opening Searcher@9fac35 main
[junit4:junit4]   2> 21288 T80 C0 P51282 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 21298 T79 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9fac35 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 21299 T79 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty3\index
[junit4:junit4]   2> 21299 T80 C0 P51282 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 21299 T80 C0 P51282 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 21299 T80 C0 P51282 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 21303 T80 C0 P51282 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 21310 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21311 T36 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:51282_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51282/solr"}
[junit4:junit4]   2> 21321 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 21321 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 21323 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 21323 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 21325 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 21325 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 21325 T108 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 21558 T14 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 21564 T14 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 21568 T14 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 21568 T14 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 21569 T14 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351854910603\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty5\
[junit4:junit4]   2> 21571 T14 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 21571 T14 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 21573 T14 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@106dc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@161401f) assuming 'simple'
[junit4:junit4]   2> 21573 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5 forceNew:false
[junit4:junit4]   2> 21574 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5
[junit4:junit4]   2> 21574 T14 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty5\index/
[junit4:junit4]   2> 21577 T14 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 21579 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5\index forceNew:false
[junit4:junit4]   2> 21584 T14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c39412 lockFactory=org.apache.lucene.store.NativeFSLockFactory@127077b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 21584 T14 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 21586 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5\index
[junit4:junit4]   2> 21588 T14 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 21588 T14 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 21588 T14 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 21590 T14 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 21599 T14 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 21599 T14 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 21612 T14 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 21624 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5
[junit4:junit4]   2> 21624 T14 oass.SolrIndexSearcher.<init> Opening Searcher@2db73e main
[junit4:junit4]   2> 21628 T14 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 21629 T14 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 21638 T109 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2db73e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 21638 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 21966 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21967 T36 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:51298_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51298/solr"}
[junit4:junit4]   2> 22033 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22033 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22033 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22033 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22034 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22034 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22034 T108 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22268 T14 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 22268 T14 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:51298/solr shardId:shard1
[junit4:junit4]   2> 22273 T14 oasc.ZkController.register We are http://127.0.0.1:51298/solr/collection1/ and leader is http://127.0.0.1:51270/solr/collection1/
[junit4:junit4]   2> 22273 T14 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51298/solr
[junit4:junit4]   2> 22273 T14 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 22275 T14 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C4 name=collection1 org.apache.solr.core.SolrCore@67e236 url=http://127.0.0.1:51298/solr/collection1 node=127.0.0.1:51298_solr
[junit4:junit4]   2> 22278 T110 C4 P51298 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 22279 T14 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22279 T110 C4 P51298 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 22280 T110 C4 P51298 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 22280 T14 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 22281 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 22283 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 22284 T110 C4 P51298 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:51270/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 22284 T110 C4 P51298 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:51298/solr START replicas=[http://127.0.0.1:51270/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 22285 T110 C4 P51298 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 22286 T110 C4 P51298 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 22286 T110 C4 P51298 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 22289 T110 C4 P51298 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 22294 T50 C1 P51270 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 22541 T14 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 22545 T14 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:51307
[junit4:junit4]   2> 22546 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 22546 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873
[junit4:junit4]   2> 22546 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873\'
[junit4:junit4]   2> 22590 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 22591 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 22591 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873
[junit4:junit4]   2> 22592 T14 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873\solr.xml
[junit4:junit4]   2> 22592 T14 oasc.CoreContainer.<init> New CoreContainer 14023097
[junit4:junit4]   2> 22594 T14 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873\'
[junit4:junit4]   2> 22594 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873\'
[junit4:junit4]   2> 22623 T14 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 22647 T14 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51254/solr
[junit4:junit4]   2> 22647 T14 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51254/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@4ba4d6
[junit4:junit4]   2> 22650 T121 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51254
[junit4:junit4]   2> 22650 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 22653 T121 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51254, initiating session
[junit4:junit4]   2> 22653 T16 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51312
[junit4:junit4]   2> 22653 T16 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51312
[junit4:junit4]   2> 22662 T18 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac0d49ff90009 with negotiated timeout 8000 for client /127.0.0.1:51312
[junit4:junit4]   2> 22662 T121 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:51254, sessionid = 0x13ac0d49ff90009, negotiated timeout = 8000
[junit4:junit4]   2> 22662 T122 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4ba4d6 name:ZooKeeperConnection Watcher:127.0.0.1:51254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 22663 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 22665 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90009 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 22667 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90009 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 22669 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51307_solr
[junit4:junit4]   2> 22671 T19 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac0d49ff90009 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51307_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51307_solr
[junit4:junit4]   2> 22673 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51307_solr
[junit4:junit4]   2> 22677 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22677 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22678 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22678 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 22679 T54 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 22679 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22680 T35 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 22682 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22682 T108 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 22683 T108 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22685 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 22685 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 22687 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 22687 T66 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 22687 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 22688 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 22688 T94 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 22688 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 22690 T78 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 22688 T43 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 22690 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 22690 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 22691 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 22691 T14 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 22701 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22702 T36 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:51298_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51298/solr"}
[junit4:junit4]   2> 22734 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 22738 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22738 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22738 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22738 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22739 T122 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> 22740 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22740 T108 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22743 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 22756 T14 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873\collection1
[junit4:junit4]   2> 22758 T14 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 22759 T14 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 22759 T14 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 22762 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873\collection1\'
[junit4:junit4]   2> 22828 T14 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 22923 T14 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 22925 T14 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 22934 T14 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 23743 T14 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 23746 T14 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 23750 T14 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 23751 T14 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 23752 T14 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351854912873\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty6\
[junit4:junit4]   2> 23752 T14 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 23752 T14 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 23754 T14 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@4ba778 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dfe491) assuming 'simple'
[junit4:junit4]   2> 23755 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty6 forceNew:false
[junit4:junit4]   2> 23755 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty6
[junit4:junit4]   2> 23755 T14 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742/jetty6\index/
[junit4:junit4]   2> 23756 T14 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty6\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 23758 T14 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty6\index forceNew:false
[junit4:junit4]   2> 23762 T14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9a44f1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1dc4cd9),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 23762 T14 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 23763 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty6\index
[junit4:junit4]   2> 23765 T14 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 23765 T14 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 23765 T14 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 23766 T14 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 23767 T14 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 23767 T14 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 23777 T14 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 23783 T14 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty6
[junit4:junit4]   2> 23783 T14 oass.SolrIndexSearcher.<init> Opening Searcher@158046e main
[junit4:junit4]   2> 23785 T14 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 23787 T14 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 23799 T14 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 23801 T123 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@158046e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2>  C2_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:51288_solr, base_url=http://127.0.0.1:51288/solr}
[junit4:junit4]   2> 23801 T96 C2 P51288 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 23801 T96 C2 P51288 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 23805 T96 C2 P51288 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:51276/solr/collection1/. core=collection1
[junit4:junit4]   2> 23805 T96 C2 P51288 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 23821 T60 C3 P51276 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 23821 T60 C3 P51276 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty2
[junit4:junit4]   2> 23825 T60 C3 P51276 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15264db lockFactory=org.apache.lucene.store.NativeFSLockFactory@eb9f0d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 23827 T60 C3 P51276 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 23827 T60 C3 P51276 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty2
[junit4:junit4]   2> 23827 T60 C3 P51276 oass.SolrIndexSearcher.<init> Opening Searcher@16a5bcb realtime
[junit4:junit4]   2> 23827 T60 C3 P51276 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23829 T60 C3 P51276 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 9
[junit4:junit4]   2> 23832 T96 C2 P51288 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 23832 T96 C2 P51288 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 23842 T62 C3 P51276 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 23843 T62 C3 P51276 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=5 
[junit4:junit4]   2> 23844 T96 C2 P51288 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4
[junit4:junit4]   2> 23849 T96 C2 P51288 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1930c3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ac5f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 23849 T96 C2 P51288 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 23850 T96 C2 P51288 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 23850 T96 C2 P51288 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1930c3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ac5f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1930c3a lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ac5f),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 23851 T96 C2 P51288 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 23851 T96 C2 P51288 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4
[junit4:junit4]   2> 23915 T96 C2 P51288 oass.SolrIndexSearcher.<init> Opening Searcher@17777a1 main
[junit4:junit4]   2> 23915 T96 C2 P51288 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23915 T95 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17777a1 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 23918 T95 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty4\index
[junit4:junit4]   2> 23918 T96 C2 P51288 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 23918 T96 C2 P51288 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 23918 T96 C2 P51288 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 23921 T96 C2 P51288 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 24017 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 24019 T36 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:51307_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51307/solr"}
[junit4:junit4]   2> 24028 T36 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:51288_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51288/solr"}
[junit4:junit4]   2> 24037 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 24039 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 24039 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 24040 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 24040 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 24040 T108 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 24040 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2>  C4_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:51298_solr, base_url=http://127.0.0.1:51298/solr}
[junit4:junit4]   2> 26071 T110 C4 P51298 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 26071 T110 C4 P51298 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 26071 T110 C4 P51298 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:51270/solr/collection1/. core=collection1
[junit4:junit4]   2> 26071 T110 C4 P51298 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 26079 T49 C1 P51270 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 26080 T49 C1 P51270 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty1
[junit4:junit4]   2> 26080 T49 C1 P51270 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 26081 T49 C1 P51270 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 2
[junit4:junit4]   2> 26082 T110 C4 P51298 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 26084 T110 C4 P51298 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 26087 T48 C1 P51270 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 26090 T110 C4 P51298 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5
[junit4:junit4]   2> 26093 T110 C4 P51298 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c39412 lockFactory=org.apache.lucene.store.NativeFSLockFactory@127077b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 26095 T110 C4 P51298 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 26095 T110 C4 P51298 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 26095 T110 C4 P51298 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c39412 lockFactory=org.apache.lucene.store.NativeFSLockFactory@127077b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c39412 lockFactory=org.apache.lucene.store.NativeFSLockFactory@127077b),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 26096 T110 C4 P51298 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 26096 T110 C4 P51298 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5
[junit4:junit4]   2> 26096 T110 C4 P51298 oass.SolrIndexSearcher.<init> Opening Searcher@1ec4535 main
[junit4:junit4]   2> 26096 T110 C4 P51298 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 26097 T109 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ec4535 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 26097 T109 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351854890742\jetty5\index
[junit4:junit4]   2> 26097 T110 C4 P51298 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 26098 T110 C4 P51298 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 26098 T110 C4 P51298 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 26106 T110 C4 P51298 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 26582 T36 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 26583 T36 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:51298_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51298/solr"}
[junit4:junit4]   2> 26593 T66 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 26594 T54 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 26595 T78 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 26595 T35 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 26595 T43 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 26595 T94 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 26596 T108 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 80160 T14 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:998)
[junit4:junit4]   2> 		at org.apache.solr.cloud.ZkController.preRegister(ZkController.java:1056)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.registerCore(CoreContainer.java:716)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.register(CoreContainer.java:696)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.load(CoreContainer.java:565)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.load(CoreContainer.java:375)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer$Initializer.initialize(CoreContainer.java:307)
[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:292)
[junit4:junit4]   2> 		at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:245)
[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] 

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

                "core":"collection1",
[junit4:junit4]   1>                 "collection":"collection1",
[junit4:junit4]   1>                 "node_name":"127.0.0.1:51276_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:51276/solr",
[junit4:junit4]   1>                 "leader":"true"},
[junit4:junit4]   1>               "127.0.0.1:51288_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:51288_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:51288/solr"},
[junit4:junit4]   1>               "127.0.0.1:51307_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:51307_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:51307/solr"}}}},
[junit4:junit4]   1>         "control_collection":{"control_shard":{"replicas":{"127.0.0.1:51261_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:51261_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:51261/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=A8D5E3DD16097CCB -Dtests.slow=true -Dtests.locale=it_IT -Dtests.timezone=America/Cancun -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] FAILURE  124s | CloudSolrServerTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 30 seconds
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([A8D5E3DD16097CCB:29336DC561561CF7]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:166)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:559)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1134)
[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> 124290 T14 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Compressing(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=1, storedFieldsIndex=DISK_DOC)), sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=it_IT, timezone=America/Cancun
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_37 (32-bit)/cpus=2,threads=3,free=24773624,total=171507712
[junit4:junit4]   2> NOTE: All tests run in this JVM: [ModifiableSolrParamsTest, QueryResponseTest, CloudSolrServerTest]
[junit4:junit4] Completed in 124.61s, 1 test, 1 failure <<< FAILURES!

[...truncated 129 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:335: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:185: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\module-build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1163: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:827: There were test failures: 43 suites, 239 tests, 1 failure

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



Mime
View raw message