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-Linux (64bit/jdk1.8.0-ea-b58) - Build # 2059 - Failure!
Date Sun, 28 Oct 2012 21:31:36 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2059/
Java: 64bit/jdk1.8.0-ea-b58 -XX:+UseConcMarkSweepGC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.client.solrj.impl.CloudSolrServerTest

Error Message:
ERROR: SolrIndexSearcher opens=12 closes=11

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=12 closes=11
	at __randomizedtesting.SeedInfo.seed([5210640E2051991]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:243)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:97)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


REGRESSION:  org.apache.solr.client.solrj.impl.CloudSolrServerTest.testDistribSearch

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

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 30 seconds
	at __randomizedtesting.SeedInfo.seed([5210640E2051991:84C78858955A79AD]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:157)
	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:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9529 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest
[junit4:junit4]   2> 1 T266 oasc.AbstractZkTestCase.<clinit> WARNING TEST_HOME() does not exist - solrj test?
[junit4:junit4]   2> 4 T266 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1351459649251
[junit4:junit4]   2> 7 T266 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 31 T267 oaz.Environment.logEnv Server environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 31 T267 oaz.Environment.logEnv Server environment:host.name=serv1
[junit4:junit4]   2> 31 T267 oaz.Environment.logEnv Server environment:java.version=1.8.0-ea
[junit4:junit4]   2> 31 T267 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 32 T267 oaz.Environment.logEnv Server environment:java.home=/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/jre
[junit4:junit4]   2> 32 T267 oaz.Environment.logEnv Server environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/zookeeper-3.3.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/easymock-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/guava-r05.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-continuation-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-http-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-security-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-server-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-webapp-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.2.0.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-testutil.jar:/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.4.jar
[junit4:junit4]   2> 33 T267 oaz.Environment.logEnv Server environment:java.library.path=/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/jre/lib/amd64/server:/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/jre/lib/amd64:/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/jre/../lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
[junit4:junit4]   2> 34 T267 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 34 T267 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4]   2> 34 T267 oaz.Environment.logEnv Server environment:os.name=Linux
[junit4:junit4]   2> 34 T267 oaz.Environment.logEnv Server environment:os.arch=amd64
[junit4:junit4]   2> 34 T267 oaz.Environment.logEnv Server environment:os.version=3.2.0-32-generic
[junit4:junit4]   2> 34 T267 oaz.Environment.logEnv Server environment:user.name=jenkins
[junit4:junit4]   2> 35 T267 oaz.Environment.logEnv Server environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 35 T267 oaz.Environment.logEnv Server environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 47 T267 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 55 T267 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 71 T267 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 109 T266 oasc.ZkTestServer.run start zk server on port:38061
[junit4:junit4]   2> 126 T266 oaz.Environment.logEnv Client environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 127 T266 oaz.Environment.logEnv Client environment:host.name=serv1
[junit4:junit4]   2> 127 T266 oaz.Environment.logEnv Client environment:java.version=1.8.0-ea
[junit4:junit4]   2> 127 T266 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 128 T266 oaz.Environment.logEnv Client environment:java.home=/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/jre
[junit4:junit4]   2> 128 T266 oaz.Environment.logEnv Client environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/zookeeper-3.3.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/easymock-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/guava-r05.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-continuation-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-http-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-security-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-server-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-webapp-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.2.0.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-testutil.jar:/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.4.jar
[junit4:junit4]   2> 130 T266 oaz.Environment.logEnv Client environment:java.library.path=/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/jre/lib/amd64/server:/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/jre/lib/amd64:/mnt/ssd/jenkins/tools/java/64bit/jdk1.8.0-ea-b58/jre/../lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
[junit4:junit4]   2> 130 T266 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 131 T266 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4]   2> 131 T266 oaz.Environment.logEnv Client environment:os.name=Linux
[junit4:junit4]   2> 131 T266 oaz.Environment.logEnv Client environment:os.arch=amd64
[junit4:junit4]   2> 132 T266 oaz.Environment.logEnv Client environment:os.version=3.2.0-32-generic
[junit4:junit4]   2> 132 T266 oaz.Environment.logEnv Client environment:user.name=jenkins
[junit4:junit4]   2> 132 T266 oaz.Environment.logEnv Client environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 133 T266 oaz.Environment.logEnv Client environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 135 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@39ceb532
[junit4:junit4]   2> 152 T272 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 152 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 153 T272 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 153 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54090
[junit4:junit4]   2> 157 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54090
[junit4:junit4]   2> 160 T270 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 239 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0000 with negotiated timeout 10000 for client /127.0.0.1:54090
[junit4:junit4]   2> 240 T272 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0000, negotiated timeout = 10000
[junit4:junit4]   2> 243 T273 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@39ceb532 name:ZooKeeperConnection Watcher:127.0.0.1:38061 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 244 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 246 T266 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 279 T271 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa945b72d0000
[junit4:junit4]   2> 280 T273 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 280 T266 oaz.ZooKeeper.close Session: 0x13aa945b72d0000 closed
[junit4:junit4]   2> 281 T268 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54090 which had sessionid 0x13aa945b72d0000
[junit4:junit4]   2> 281 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7fe4fe5e
[junit4:junit4]   2> 282 T274 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 282 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 282 T274 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 282 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54091
[junit4:junit4]   2> 282 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54091
[junit4:junit4]   2> 283 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0001 with negotiated timeout 10000 for client /127.0.0.1:54091
[junit4:junit4]   2> 284 T274 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0001, negotiated timeout = 10000
[junit4:junit4]   2> 284 T275 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7fe4fe5e name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 284 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 289 T266 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 293 T266 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 294 T266 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 296 T266 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 298 T266 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 299 T266 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 302 T266 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 303 T266 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 317 T266 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 318 T266 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 325 T266 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
[junit4:junit4]   2> 325 T266 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
[junit4:junit4]   2> 326 T266 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
[junit4:junit4]   2> 326 T266 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
[junit4:junit4]   2> 326 T266 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4:junit4]   2> 327 T266 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
[junit4:junit4]   2> 327 T266 oasc.AbstractZkTestCase.putConfig skipping /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
[junit4:junit4]   2> 327 T271 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa945b72d0001
[junit4:junit4]   2> 328 T275 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 328 T266 oaz.ZooKeeper.close Session: 0x13aa945b72d0001 closed
[junit4:junit4]   2> 328 T268 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54091 which had sessionid 0x13aa945b72d0001
[junit4:junit4]   2> 363 T266 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 366 T266 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55942
[junit4:junit4]   2> 366 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 367 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575
[junit4:junit4]   2> 367 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575/'
[junit4:junit4]   2> 385 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 385 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 386 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575
[junit4:junit4]   2> 386 T266 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575/solr.xml
[junit4:junit4]   2> 387 T266 oasc.CoreContainer.<init> New CoreContainer 318060718
[junit4:junit4]   2> 387 T266 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575/'
[junit4:junit4]   2> 388 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575/'
[junit4:junit4]   2> 403 T266 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 418 T266 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:38061/solr
[junit4:junit4]   2> 427 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@9d5d2a6
[junit4:junit4]   2> 428 T285 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 428 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 429 T285 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 429 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54092
[junit4:junit4]   2> 430 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54092
[junit4:junit4]   2> 432 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0002 with negotiated timeout 8000 for client /127.0.0.1:54092
[junit4:junit4]   2> 432 T285 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0002, negotiated timeout = 8000
[junit4:junit4]   2> 433 T286 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9d5d2a6 name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 433 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 440 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 444 T266 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 447 T266 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55942_solr
[junit4:junit4]   2> 449 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55942_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55942_solr
[junit4:junit4]   2> 449 T266 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55942_solr
[junit4:junit4]   2> 452 T266 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 452 T266 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 453 T266 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 453 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 453 T266 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 454 T266 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 454 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 454 T266 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 455 T266 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 455 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 459 T266 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 468 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 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> 469 T266 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 471 T266 oasc.Overseer.start Overseer (id=88569259578163202-127.0.0.1:55942_solr-n_0000000000) starting
[junit4:junit4]   2> 472 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 477 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 478 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 480 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 480 T288 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 481 T266 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 483 T266 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 491 T287 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 494 T266 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575/collection1
[junit4:junit4]   2> 494 T266 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 495 T266 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 495 T266 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 496 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575/collection1/'
[junit4:junit4]   2> 520 T266 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 551 T266 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 552 T266 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 556 T266 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 936 T266 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 939 T266 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 941 T266 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 942 T266 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 946 T266 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1351459649575/collection1/, dataDir=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/control/data/
[junit4:junit4]   2> 947 T266 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 947 T266 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 948 T266 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@7c41ed16 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c347f15) assuming 'simple'
[junit4:junit4]   2> 949 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/control/data forceNew:false
[junit4:junit4]   2> 949 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/control/data
[junit4:junit4]   2> 949 T266 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/control/data/index/
[junit4:junit4]   2> 950 T266 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 950 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/control/data/index forceNew:false
[junit4:junit4]   2> 952 T266 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4f798e12 lockFactory=org.apache.lucene.store.NativeFSLockFactory@491f0886),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 952 T266 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 953 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/control/data/index
[junit4:junit4]   2> 953 T266 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 954 T266 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 954 T266 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 955 T266 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 955 T266 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 955 T266 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 957 T266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 961 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/control/data
[junit4:junit4]   2> 962 T266 oass.SolrIndexSearcher.<init> Opening Searcher@7d02f37b main
[junit4:junit4]   2> 963 T266 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 964 T266 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 965 T289 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7d02f37b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 965 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 966 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x33 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 969 T266 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 969 T266 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55942/solr shardId:control_shard
[junit4:junit4]   2> 972 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 976 T266 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 981 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:delete cxid:0x42 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> 982 T266 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 983 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 994 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 995 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55942/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55942_solr",
[junit4:junit4]   2> 	  "shard":"control_shard"}
[junit4:junit4]   2> 998 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1017 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1486 T266 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1487 T266 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1487 T266 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55942/solr/collection1/
[junit4:junit4]   2> 1488 T266 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1488 T266 oasc.SyncStrategy.syncToMe http://127.0.0.1:55942/solr/collection1/ has no replicas
[junit4:junit4]   2> 1489 T266 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55942/solr/collection1/
[junit4:junit4]   2> 1489 T266 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1493 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0002 type:create cxid:0x69 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1521 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1525 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1546 T266 oasc.ZkController.register We are http://127.0.0.1:55942/solr/collection1/ and leader is http://127.0.0.1:55942/solr/collection1/
[junit4:junit4]   2> 1546 T266 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55942/solr
[junit4:junit4]   2> 1546 T266 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1547 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1548 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1549 T266 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 1549 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1550 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1552 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 1556 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2931fa11
[junit4:junit4]   2> 1556 T290 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 1556 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1557 T290 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 1557 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54093
[junit4:junit4]   2> 1558 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54093
[junit4:junit4]   2> 1558 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0003 with negotiated timeout 10000 for client /127.0.0.1:54093
[junit4:junit4]   2> 1558 T290 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0003, negotiated timeout = 10000
[junit4:junit4]   2> 1559 T291 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2931fa11 name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1559 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1560 T266 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1562 T266 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 1588 T266 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1590 T266 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:58852
[junit4:junit4]   2> 1591 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1591 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808
[junit4:junit4]   2> 1592 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808/'
[junit4:junit4]   2> 1605 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1606 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1606 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808
[junit4:junit4]   2> 1606 T266 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808/solr.xml
[junit4:junit4]   2> 1607 T266 oasc.CoreContainer.<init> New CoreContainer 529037436
[junit4:junit4]   2> 1607 T266 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808/'
[junit4:junit4]   2> 1608 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808/'
[junit4:junit4]   2> 1620 T266 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1633 T266 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:38061/solr
[junit4:junit4]   2> 1634 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@58f5a0f0
[junit4:junit4]   2> 1635 T301 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 1634 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1636 T301 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 1636 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54094
[junit4:junit4]   2> 1636 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54094
[junit4:junit4]   2> 1637 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0004 with negotiated timeout 8000 for client /127.0.0.1:54094
[junit4:junit4]   2> 1637 T301 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0004, negotiated timeout = 8000
[junit4:junit4]   2> 1638 T302 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@58f5a0f0 name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1638 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1639 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1639 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1640 T266 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58852_solr
[junit4:junit4]   2> 1641 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58852_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58852_solr
[junit4:junit4]   2> 1642 T266 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58852_solr
[junit4:junit4]   2> 1644 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1645 T291 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 1645 T266 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1645 T286 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 1645 T266 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1645 T266 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1646 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1646 T266 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1646 T266 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1647 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1647 T266 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1647 T266 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1648 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1653 T266 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1657 T266 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808/collection1
[junit4:junit4]   2> 1658 T266 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 1658 T266 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1659 T266 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 1660 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808/collection1/'
[junit4:junit4]   2> 1680 T266 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1709 T266 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1710 T266 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1713 T266 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2028 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2029 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55942/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55942_solr",
[junit4:junit4]   2> 	  "shard":"control_shard"}
[junit4:junit4]   2> 2031 T266 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2034 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2034 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2035 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2035 T266 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2038 T266 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 2039 T266 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 2040 T266 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1351459650808/collection1/, dataDir=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1/
[junit4:junit4]   2> 2040 T266 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 2040 T266 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2041 T266 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@509d56cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9add614; maxCacheMB=0.904296875 maxMergeSizeMB=0.443359375)) assuming 'simple'
[junit4:junit4]   2> 2042 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1 forceNew:false
[junit4:junit4]   2> 2042 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1
[junit4:junit4]   2> 2043 T266 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1/index/
[junit4:junit4]   2> 2044 T266 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2044 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1/index forceNew:false
[junit4:junit4]   2> 2046 T266 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7d4b1e4e lockFactory=org.apache.lucene.store.NativeFSLockFactory@3e444a80),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2047 T266 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 2047 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1/index
[junit4:junit4]   2> 2048 T266 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2049 T266 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2049 T266 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2050 T266 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2050 T266 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2051 T266 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2054 T266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2058 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1
[junit4:junit4]   2> 2058 T266 oass.SolrIndexSearcher.<init> Opening Searcher@1ed39256 main
[junit4:junit4]   2> 2059 T266 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2060 T266 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2061 T303 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ed39256 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2061 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2538 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2539 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:58852/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:58852_solr",
[junit4:junit4]   2> 	  "shard":null}
[junit4:junit4]   2> 2539 T287 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 2546 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2547 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2547 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2562 T266 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 2563 T266 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:58852/solr shardId:shard1
[junit4:junit4]   2> 2564 T266 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 2569 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0004 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> 2569 T266 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2570 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0004 type:create cxid:0x29 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2572 T266 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2572 T266 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2572 T266 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58852/solr/collection1/
[junit4:junit4]   2> 2573 T266 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2573 T266 oasc.SyncStrategy.syncToMe http://127.0.0.1:58852/solr/collection1/ has no replicas
[junit4:junit4]   2> 2574 T266 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58852/solr/collection1/
[junit4:junit4]   2> 2574 T266 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 2578 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0004 type:create cxid:0x33 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3050 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3057 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3057 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3057 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3080 T266 oasc.ZkController.register We are http://127.0.0.1:58852/solr/collection1/ and leader is http://127.0.0.1:58852/solr/collection1/
[junit4:junit4]   2> 3081 T266 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58852/solr
[junit4:junit4]   2> 3081 T266 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3081 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3084 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3085 T266 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 3085 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3085 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3115 T266 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3118 T266 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:47691
[junit4:junit4]   2> 3119 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3119 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334
[junit4:junit4]   2> 3119 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334/'
[junit4:junit4]   2> 3131 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3132 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3132 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334
[junit4:junit4]   2> 3132 T266 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334/solr.xml
[junit4:junit4]   2> 3133 T266 oasc.CoreContainer.<init> New CoreContainer 1436004715
[junit4:junit4]   2> 3133 T266 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334/'
[junit4:junit4]   2> 3133 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334/'
[junit4:junit4]   2> 3144 T266 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3151 T266 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:38061/solr
[junit4:junit4]   2> 3152 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1535d274
[junit4:junit4]   2> 3153 T313 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 3152 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3154 T313 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 3154 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54095
[junit4:junit4]   2> 3154 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54095
[junit4:junit4]   2> 3155 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0005 with negotiated timeout 8000 for client /127.0.0.1:54095
[junit4:junit4]   2> 3155 T313 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0005, negotiated timeout = 8000
[junit4:junit4]   2> 3156 T314 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1535d274 name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3159 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3160 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3161 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3162 T266 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47691_solr
[junit4:junit4]   2> 3162 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:47691_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:47691_solr
[junit4:junit4]   2> 3163 T266 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:47691_solr
[junit4:junit4]   2> 3165 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3165 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3165 T286 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3165 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3166 T266 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3166 T302 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3166 T266 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3167 T291 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3167 T266 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3167 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3168 T266 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3168 T266 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3169 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3169 T266 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3170 T266 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3170 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3177 T266 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3182 T266 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334/collection1
[junit4:junit4]   2> 3183 T266 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3184 T266 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3184 T266 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3186 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334/collection1/'
[junit4:junit4]   2> 3222 T266 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3248 T266 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3250 T266 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3253 T266 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3508 T266 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3510 T266 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3512 T266 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 3512 T266 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 3513 T266 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1351459652334/collection1/, dataDir=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2/
[junit4:junit4]   2> 3513 T266 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 3513 T266 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 3514 T266 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@75a67930 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@45e7aa8) assuming 'simple'
[junit4:junit4]   2> 3514 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2 forceNew:false
[junit4:junit4]   2> 3515 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2
[junit4:junit4]   2> 3515 T266 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2/index/
[junit4:junit4]   2> 3515 T266 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3516 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2/index forceNew:false
[junit4:junit4]   2> 3517 T266 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5fe3f95f lockFactory=org.apache.lucene.store.NativeFSLockFactory@5e2444e7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3518 T266 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 3518 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2/index
[junit4:junit4]   2> 3519 T266 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3520 T266 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3520 T266 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3520 T266 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3521 T266 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3521 T266 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3525 T266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3528 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2
[junit4:junit4]   2> 3529 T266 oass.SolrIndexSearcher.<init> Opening Searcher@7e4249c5 main
[junit4:junit4]   2> 3530 T266 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3531 T266 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3532 T315 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e4249c5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3532 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3561 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3562 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:58852/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:58852_solr",
[junit4:junit4]   2> 	  "shard":"shard1"}
[junit4:junit4]   2> 3565 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47691/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47691_solr",
[junit4:junit4]   2> 	  "shard":null}
[junit4:junit4]   2> 3570 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3570 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3570 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3570 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4034 T266 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4035 T266 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:47691/solr shardId:shard2
[junit4:junit4]   2> 4036 T266 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 4044 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0005 type:delete cxid:0x25 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 4045 T266 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4046 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0005 type:create cxid:0x26 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4048 T266 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4049 T266 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4049 T266 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:47691/solr/collection1/
[junit4:junit4]   2> 4050 T266 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4050 T266 oasc.SyncStrategy.syncToMe http://127.0.0.1:47691/solr/collection1/ has no replicas
[junit4:junit4]   2> 4051 T266 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:47691/solr/collection1/
[junit4:junit4]   2> 4052 T266 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 4056 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0005 type:create cxid:0x2f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4075 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4080 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4080 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4080 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4080 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4108 T266 oasc.ZkController.register We are http://127.0.0.1:47691/solr/collection1/ and leader is http://127.0.0.1:47691/solr/collection1/
[junit4:junit4]   2> 4109 T266 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:47691/solr
[junit4:junit4]   2> 4110 T266 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4110 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4113 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4114 T266 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 4115 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4116 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4161 T266 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4165 T266 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:54081
[junit4:junit4]   2> 4165 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4166 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365
[junit4:junit4]   2> 4166 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365/'
[junit4:junit4]   2> 4178 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4178 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4178 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365
[junit4:junit4]   2> 4179 T266 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365/solr.xml
[junit4:junit4]   2> 4179 T266 oasc.CoreContainer.<init> New CoreContainer 419351481
[junit4:junit4]   2> 4179 T266 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365/'
[junit4:junit4]   2> 4180 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365/'
[junit4:junit4]   2> 4191 T266 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4198 T266 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:38061/solr
[junit4:junit4]   2> 4198 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@47d1b0cf
[junit4:junit4]   2> 4199 T325 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 4199 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4200 T325 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 4200 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54096
[junit4:junit4]   2> 4201 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54096
[junit4:junit4]   2> 4202 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0006 with negotiated timeout 8000 for client /127.0.0.1:54096
[junit4:junit4]   2> 4202 T325 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0006, negotiated timeout = 8000
[junit4:junit4]   2> 4202 T326 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47d1b0cf name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4202 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4203 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4204 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4205 T266 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54081_solr
[junit4:junit4]   2> 4205 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:54081_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:54081_solr
[junit4:junit4]   2> 4206 T266 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54081_solr
[junit4:junit4]   2> 4207 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4207 T286 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4207 T291 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4207 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4207 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4208 T314 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4208 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4208 T302 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4208 T266 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4208 T266 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4209 T266 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4209 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4209 T266 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4210 T266 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4210 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4210 T266 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4210 T266 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4211 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4217 T266 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4224 T266 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365/collection1
[junit4:junit4]   2> 4225 T266 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4226 T266 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4226 T266 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4227 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365/collection1/'
[junit4:junit4]   2> 4246 T266 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4277 T266 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4279 T266 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4282 T266 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4536 T266 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4538 T266 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4539 T266 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 4540 T266 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 4540 T266 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1351459653365/collection1/, dataDir=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3/
[junit4:junit4]   2> 4540 T266 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 4541 T266 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 4542 T266 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/index6437864379tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@5fb4d0df) assuming 'simple'
[junit4:junit4]   2> 4542 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3 forceNew:false
[junit4:junit4]   2> 4543 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3
[junit4:junit4]   2> 4543 T266 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3/index/
[junit4:junit4]   2> 4544 T266 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4544 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3/index forceNew:false
[junit4:junit4]   2> 4545 T266 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@278bf06a lockFactory=org.apache.lucene.store.NativeFSLockFactory@d050603),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4546 T266 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4546 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3/index
[junit4:junit4]   2> 4547 T266 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4547 T266 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4547 T266 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4548 T266 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4548 T266 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4548 T266 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4550 T266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4552 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3
[junit4:junit4]   2> 4553 T266 oass.SolrIndexSearcher.<init> Opening Searcher@443f2a2c main
[junit4:junit4]   2> 4553 T266 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4554 T266 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4555 T327 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@443f2a2c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4555 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4589 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4589 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47691/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47691_solr",
[junit4:junit4]   2> 	  "shard":"shard2"}
[junit4:junit4]   2> 4592 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:54081/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:54081_solr",
[junit4:junit4]   2> 	  "shard":null}
[junit4:junit4]   2> 4594 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4594 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4594 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4594 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4594 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5056 T266 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5057 T266 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:54081/solr shardId:shard1
[junit4:junit4]   2> 5062 T266 oasc.ZkController.register We are http://127.0.0.1:54081/solr/collection1/ and leader is http://127.0.0.1:58852/solr/collection1/
[junit4:junit4]   2> 5063 T266 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54081/solr
[junit4:junit4]   2> 5064 T266 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 5065 T266 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C29 name=collection1 org.apache.solr.core.SolrCore@45c2e2b1 url=http://127.0.0.1:54081/solr/collection1 node=127.0.0.1:54081_solr
[junit4:junit4]   2> 5071 T328 C29 P54081 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 5071 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5072 T328 C29 P54081 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 5072 T328 C29 P54081 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5073 T266 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 5073 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5073 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5076 T328 C29 P54081 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:58852/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 5079 T328 C29 P54081 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 5082 T328 C29 P54081 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:54081/solr START replicas=[http://127.0.0.1:58852/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 5082 T328 C29 P54081 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 5083 T328 C29 P54081 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 5084 T328 C29 P54081 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 5084 T328 C29 P54081 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C30 name=collection1 org.apache.solr.core.SolrCore@9539c21 url=http://127.0.0.1:58852/solr/collection1 node=127.0.0.1:58852_solr
[junit4:junit4]   2> 5088 T299 C30 P58852 REQ /get {getVersions=100&qt=/get&distrib=false&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5101 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5102 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:54081/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:54081_solr",
[junit4:junit4]   2> 	  "shard":"shard1"}
[junit4:junit4]   2> 5104 T266 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5105 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5105 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5105 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5105 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5105 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5107 T266 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:35966
[junit4:junit4]   2> 5108 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5108 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323
[junit4:junit4]   2> 5109 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323/'
[junit4:junit4]   2> 5121 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5122 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5122 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323
[junit4:junit4]   2> 5122 T266 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323/solr.xml
[junit4:junit4]   2> 5123 T266 oasc.CoreContainer.<init> New CoreContainer 2031967407
[junit4:junit4]   2> 5123 T266 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323/'
[junit4:junit4]   2> 5123 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323/'
[junit4:junit4]   2> 5142 T266 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5151 T266 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:38061/solr
[junit4:junit4]   2> 5152 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@48fe59a2
[junit4:junit4]   2> 5153 T339 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 5153 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5154 T339 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 5154 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54099
[junit4:junit4]   2> 5154 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54099
[junit4:junit4]   2> 5156 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0007 with negotiated timeout 8000 for client /127.0.0.1:54099
[junit4:junit4]   2> 5156 T339 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0007, negotiated timeout = 8000
[junit4:junit4]   2> 5156 T340 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@48fe59a2 name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5156 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5157 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5158 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5159 T266 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35966_solr
[junit4:junit4]   2> 5159 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:35966_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:35966_solr
[junit4:junit4]   2> 5160 T266 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:35966_solr
[junit4:junit4]   2> 5161 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5161 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5161 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5161 T326 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5161 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5162 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5162 T266 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5162 T302 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5162 T286 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5162 T266 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5162 T291 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5163 T266 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5163 T314 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5163 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5163 T266 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5164 T266 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5164 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5164 T266 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5164 T266 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5165 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5169 T266 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5173 T266 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323/collection1
[junit4:junit4]   2> 5173 T266 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5174 T266 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5174 T266 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5175 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323/collection1/'
[junit4:junit4]   2> 5199 T266 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5227 T266 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5228 T266 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5231 T266 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5505 T266 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5508 T266 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5509 T266 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 5510 T266 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 5510 T266 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty4-1351459654323/collection1/, dataDir=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4/
[junit4:junit4]   2> 5511 T266 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 5511 T266 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5512 T266 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6b81ad9f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eadd8dd) assuming 'simple'
[junit4:junit4]   2> 5512 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4 forceNew:false
[junit4:junit4]   2> 5512 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4
[junit4:junit4]   2> 5513 T266 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4/index/
[junit4:junit4]   2> 5513 T266 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5514 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4/index forceNew:false
[junit4:junit4]   2> 5515 T266 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3023c259 lockFactory=org.apache.lucene.store.NativeFSLockFactory@786d2a07),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5515 T266 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5516 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4/index
[junit4:junit4]   2> 5516 T266 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5517 T266 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5517 T266 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5517 T266 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5518 T266 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5518 T266 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5520 T266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5522 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4
[junit4:junit4]   2> 5523 T266 oass.SolrIndexSearcher.<init> Opening Searcher@5a554f1c main
[junit4:junit4]   2> 5523 T266 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5524 T266 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5525 T341 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5a554f1c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5525 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5608 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5609 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:35966/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:35966_solr",
[junit4:junit4]   2> 	  "shard":null}
[junit4:junit4]   2> 5614 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5615 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5614 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5614 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5614 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5615 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 6027 T266 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6027 T266 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:35966/solr shardId:shard2
[junit4:junit4]   2> 6031 T266 oasc.ZkController.register We are http://127.0.0.1:35966/solr/collection1/ and leader is http://127.0.0.1:47691/solr/collection1/
[junit4:junit4]   2> 6032 T266 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:35966/solr
[junit4:junit4]   2> 6032 T266 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 6032 T266 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C31 name=collection1 org.apache.solr.core.SolrCore@cec4859 url=http://127.0.0.1:35966/solr/collection1 node=127.0.0.1:35966_solr
[junit4:junit4]   2> 6033 T342 C31 P35966 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 6033 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6035 T342 C31 P35966 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 6035 T342 C31 P35966 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6036 T266 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 6036 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6036 T342 C31 P35966 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:47691/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 6037 T342 C31 P35966 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:35966/solr START replicas=[http://127.0.0.1:47691/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 6036 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6037 T342 C31 P35966 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 6037 T342 C31 P35966 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 6038 T342 C31 P35966 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 6038 T342 C31 P35966 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C32 name=collection1 org.apache.solr.core.SolrCore@c94b66c url=http://127.0.0.1:47691/solr/collection1 node=127.0.0.1:47691_solr
[junit4:junit4]   2> 6041 T310 C32 P47691 REQ /get {getVersions=100&qt=/get&distrib=false&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6067 T266 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6069 T266 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:44495
[junit4:junit4]   2> 6070 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6070 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287
[junit4:junit4]   2> 6071 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287/'
[junit4:junit4]   2> 6081 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6082 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6082 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287
[junit4:junit4]   2> 6082 T266 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287/solr.xml
[junit4:junit4]   2> 6083 T266 oasc.CoreContainer.<init> New CoreContainer 797332778
[junit4:junit4]   2> 6083 T266 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287/'
[junit4:junit4]   2> 6083 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287/'
[junit4:junit4]   2> 6095 T266 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6101 T266 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:38061/solr
[junit4:junit4]   2> 6102 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1940fa6a
[junit4:junit4]   2> 6103 T353 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 6103 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6104 T353 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 6104 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54103
[junit4:junit4]   2> 6104 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54103
[junit4:junit4]   2> 6105 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0008 with negotiated timeout 8000 for client /127.0.0.1:54103
[junit4:junit4]   2> 6105 T353 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0008, negotiated timeout = 8000
[junit4:junit4]   2> 6105 T354 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1940fa6a name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6105 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6106 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0008 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6107 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0008 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6107 T266 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44495_solr
[junit4:junit4]   2> 6108 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0008 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:44495_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:44495_solr
[junit4:junit4]   2> 6108 T266 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44495_solr
[junit4:junit4]   2> 6109 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 6109 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 6109 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 6109 T291 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 6110 T326 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 6110 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6110 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6110 T340 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 6110 T314 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 6110 T302 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 6110 T266 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6110 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6111 T286 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4:junit4]   2> 6111 T266 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6111 T266 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6112 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6112 T266 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6112 T266 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6113 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6113 T266 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6114 T266 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6114 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6118 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6119 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:35966/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:35966_solr",
[junit4:junit4]   2> 	  "shard":"shard2"}
[junit4:junit4]   2> 6120 T266 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6123 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6123 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6123 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6123 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6123 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6123 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6123 T354 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> 6133 T266 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287/collection1
[junit4:junit4]   2> 6134 T266 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6135 T266 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6135 T266 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6136 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287/collection1/'
[junit4:junit4]   2> 6163 T266 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 6190 T266 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6192 T266 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6195 T266 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6465 T266 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6467 T266 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6468 T266 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 6469 T266 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 6469 T266 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty5-1351459655287/collection1/, dataDir=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty5/
[junit4:junit4]   2> 6484 T266 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 6484 T266 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 6485 T266 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@3e4cb61c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e480838) assuming 'simple'
[junit4:junit4]   2> 6486 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty5 forceNew:false
[junit4:junit4]   2> 6486 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty5
[junit4:junit4]   2> 6486 T266 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty5/index/
[junit4:junit4]   2> 6487 T266 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty5/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6487 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty5/index forceNew:false
[junit4:junit4]   2> 6489 T266 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@77e1182 lockFactory=org.apache.lucene.store.NativeFSLockFactory@614e3bca),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6489 T266 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6490 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty5/index
[junit4:junit4]   2> 6490 T266 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6491 T266 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6491 T266 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6491 T266 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6492 T266 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6492 T266 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6494 T266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6497 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty5
[junit4:junit4]   2> 6498 T266 oass.SolrIndexSearcher.<init> Opening Searcher@2bb5d60d main
[junit4:junit4]   2> 6499 T266 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6500 T266 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6501 T355 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2bb5d60d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6501 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6627 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6628 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44495/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44495_solr",
[junit4:junit4]   2> 	  "shard":null}
[junit4:junit4]   2> 6633 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6633 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6633 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6633 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6633 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 6633 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2>  C29_STATE=coll:collection1 core:collection1 props:{roles=null, core=collection1, collection=collection1, state=recovering, base_url=http://127.0.0.1:54081/solr, node_name=127.0.0.1:54081_solr, shard=shard1}
[junit4:junit4]   2> 8088 T328 C29 P54081 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 8089 T328 C29 P54081 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 8089 T328 C29 P54081 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:58852/solr/collection1/. core=collection1
[junit4:junit4]   2> 8090 T328 C29 P54081 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8100 T297 C30 P58852 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 8107 T297 C30 P58852 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8109 T297 C30 P58852 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1
[junit4:junit4]   2> 8110 T297 C30 P58852 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7d4b1e4e lockFactory=org.apache.lucene.store.NativeFSLockFactory@3e444a80),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8110 T297 C30 P58852 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8110 T297 C30 P58852 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty1
[junit4:junit4]   2> 8111 T297 C30 P58852 oass.SolrIndexSearcher.<init> Opening Searcher@28b7f1f6 realtime
[junit4:junit4]   2> 8111 T297 C30 P58852 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8111 T297 C30 P58852 /update {commit=true&commit_end_point=true&waitSearcher=true&openSearcher=false&softCommit=false&version=2&wt=javabin} {commit=} 0 16
[junit4:junit4]   2> 8112 T328 C29 P54081 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 8112 T328 C29 P54081 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 8116 T328 C29 P54081 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 8119 T299 C30 P58852 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 8119 T299 C30 P58852 REQ /replication {command=indexversion&qt=/replication&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 8120 T328 C29 P54081 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3
[junit4:junit4]   2> 8121 T328 C29 P54081 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@278bf06a lockFactory=org.apache.lucene.store.NativeFSLockFactory@d050603),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8122 T328 C29 P54081 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8122 T328 C29 P54081 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8123 T328 C29 P54081 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@278bf06a lockFactory=org.apache.lucene.store.NativeFSLockFactory@d050603),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@278bf06a lockFactory=org.apache.lucene.store.NativeFSLockFactory@d050603),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 8123 T328 C29 P54081 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 8124 T328 C29 P54081 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3
[junit4:junit4]   2> 8124 T328 C29 P54081 oass.SolrIndexSearcher.<init> Opening Searcher@252d66f main
[junit4:junit4]   2> 8125 T328 C29 P54081 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8125 T327 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@252d66f main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 8125 T327 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty3/index
[junit4:junit4]   2> 8126 T328 C29 P54081 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 8126 T328 C29 P54081 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 8126 T328 C29 P54081 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8128 T328 C29 P54081 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 8143 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8143 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:54081/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:54081_solr",
[junit4:junit4]   2> 	  "shard":"shard1"}
[junit4:junit4]   2> 8146 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 8146 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 8146 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 8146 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 8146 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 8147 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2>  C31_STATE=coll:collection1 core:collection1 props:{roles=null, core=collection1, collection=collection1, state=recovering, base_url=http://127.0.0.1:35966/solr, node_name=127.0.0.1:35966_solr, shard=shard2}
[junit4:junit4]   2> 9044 T342 C31 P35966 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 9044 T342 C31 P35966 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 9045 T342 C31 P35966 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:47691/solr/collection1/. core=collection1
[junit4:junit4]   2> 9045 T342 C31 P35966 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9051 T309 C32 P47691 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9052 T309 C32 P47691 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2
[junit4:junit4]   2> 9055 T309 C32 P47691 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5fe3f95f lockFactory=org.apache.lucene.store.NativeFSLockFactory@5e2444e7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9056 T309 C32 P47691 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9057 T309 C32 P47691 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2
[junit4:junit4]   2> 9057 T309 C32 P47691 oass.SolrIndexSearcher.<init> Opening Searcher@6ad8a8aa realtime
[junit4:junit4]   2> 9058 T309 C32 P47691 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9058 T309 C32 P47691 /update {commit=true&commit_end_point=true&waitSearcher=true&openSearcher=false&softCommit=false&version=2&wt=javabin} {commit=} 0 8
[junit4:junit4]   2> 9060 T342 C31 P35966 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 9060 T342 C31 P35966 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 9064 T311 C32 P47691 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 9064 T311 C32 P47691 REQ /replication {command=indexversion&qt=/replication&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 9065 T342 C31 P35966 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4
[junit4:junit4]   2> 9067 T342 C31 P35966 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3023c259 lockFactory=org.apache.lucene.store.NativeFSLockFactory@786d2a07),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9068 T342 C31 P35966 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9068 T342 C31 P35966 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9069 T342 C31 P35966 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3023c259 lockFactory=org.apache.lucene.store.NativeFSLockFactory@786d2a07),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3023c259 lockFactory=org.apache.lucene.store.NativeFSLockFactory@786d2a07),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 9069 T342 C31 P35966 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 9070 T342 C31 P35966 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4
[junit4:junit4]   2> 9070 T342 C31 P35966 oass.SolrIndexSearcher.<init> Opening Searcher@6628d092 main
[junit4:junit4]   2> 9071 T342 C31 P35966 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9071 T341 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6628d092 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 9071 T341 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty4/index
[junit4:junit4]   2> 9072 T342 C31 P35966 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 9072 T342 C31 P35966 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 9072 T342 C31 P35966 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9074 T342 C31 P35966 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 9151 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9152 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:35966/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:35966_solr",
[junit4:junit4]   2> 	  "shard":"shard2"}
[junit4:junit4]   2> 9156 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 9156 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 9156 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 9156 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 9156 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 9156 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 66519 T266 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.register(CoreContainer.java:657)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.load(CoreContainer.java:530)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.load(CoreContainer.java:351)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer$Initializer.initialize(CoreContainer.java:303)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:107)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.FilterHolder.doStart(FilterHolder.java:114)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:59)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:754)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1227)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1263)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:965)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:923)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:333)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:173)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:169)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:60)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:225)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:205)
[junit4:junit4]   2> 		at org.apache.solr.BaseDistributedSearchTestCase.createJetty(BaseDistributedSearchTestCase.java:262)
[junit4:junit4]   2> 		at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createJettys(AbstractFullDistribZkTestBase.java: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:57)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 66521 T266 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 66521 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 66522 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 66552 T266 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 66554 T266 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:41871
[junit4:junit4]   2> 66556 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 66556 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772
[junit4:junit4]   2> 66556 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772/'
[junit4:junit4]   2> 66572 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 66572 T266 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 66572 T266 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772
[junit4:junit4]   2> 66573 T266 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772/solr.xml
[junit4:junit4]   2> 66573 T266 oasc.CoreContainer.<init> New CoreContainer 1955305805
[junit4:junit4]   2> 66573 T266 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772/'
[junit4:junit4]   2> 66574 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772/'
[junit4:junit4]   2> 66586 T266 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 66592 T266 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:38061/solr
[junit4:junit4]   2> 66593 T266 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:38061/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@780bfb81
[junit4:junit4]   2> 66594 T365 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:38061
[junit4:junit4]   2> 66594 T266 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 66595 T365 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:38061, initiating session
[junit4:junit4]   2> 66595 T268 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54113
[junit4:junit4]   2> 66595 T268 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54113
[junit4:junit4]   2> 66596 T270 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa945b72d0009 with negotiated timeout 8000 for client /127.0.0.1:54113
[junit4:junit4]   2> 66596 T365 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:38061, sessionid = 0x13aa945b72d0009, negotiated timeout = 8000
[junit4:junit4]   2> 66596 T366 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@780bfb81 name:ZooKeeperConnection Watcher:127.0.0.1:38061/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 66596 T266 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 66597 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0009 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 66598 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0009 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 66599 T266 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41871_solr
[junit4:junit4]   2> 66599 T271 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa945b72d0009 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:41871_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:41871_solr
[junit4:junit4]   2> 66599 T266 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41871_solr
[junit4:junit4]   2> 66600 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 66601 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 66600 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 66601 T340 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 66600 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (6)
[junit4:junit4]   2> 66602 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 66602 T354 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 66602 T302 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 66602 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 66602 T266 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 66602 T266 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 66603 T314 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 66603 T326 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 66603 T286 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 66603 T291 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4:junit4]   2> 66603 T266 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 66604 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 66604 T266 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 66604 T266 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 66605 T266 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 66605 T266 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 66605 T266 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 66605 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 66616 T3 oasc.SolrCore.finalize SEVERE REFCOUNT ERROR: unreferenced org.apache.solr.core.SolrCore@1264419f (collection1) has a reference count of 1
[junit4:junit4]   2> 66616 T3 oasu.ConcurrentLRUCache.finalize SEVERE ConcurrentLRUCache was not destroyed prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!!
[junit4:junit4]   2> 66617 T266 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 66621 T266 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772/collection1
[junit4:junit4]   2> 66621 T266 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 66622 T266 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 66622 T266 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 66623 T266 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772/collection1/'
[junit4:junit4]   2> 66642 T266 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 66664 T266 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 66665 T266 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 66670 T266 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 66932 T266 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 66934 T266 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 66936 T266 oass.IndexSchema.readSchema WARNING Field text is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 66936 T266 oass.IndexSchema.readSchema WARNING Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4:junit4]   2> 66937 T266 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty6-1351459715772/collection1/, dataDir=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty6/
[junit4:junit4]   2> 66945 T266 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 66946 T266 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 66950 T266 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@461d4103 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a974feb; maxCacheMB=0.626953125 maxMergeSizeMB=0.5205078125)) assuming 'simple'
[junit4:junit4]   2> 66950 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty6 forceNew:false
[junit4:junit4]   2> 66950 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty6
[junit4:junit4]   2> 66951 T266 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty6/index/
[junit4:junit4]   2> 66951 T266 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty6/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 66952 T266 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty6/index forceNew:false
[junit4:junit4]   2> 66953 T266 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@19a37fc7 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5845a42e; maxCacheMB=0.275390625 maxMergeSizeMB=0.0263671875)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 66953 T266 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 66954 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty6/index
[junit4:junit4]   2> 66955 T266 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 66955 T266 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 66955 T266 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 66955 T266 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 66956 T266 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 66956 T266 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 66958 T266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 66960 T266 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty6
[junit4:junit4]   2> 66961 T266 oass.SolrIndexSearcher.<init> Opening Searcher@3abe62c1 main
[junit4:junit4]   2> 66962 T266 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 66962 T266 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 66963 T367 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3abe62c1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 66963 T266 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 67335 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 67336 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:41871/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:41871_solr",
[junit4:junit4]   2> 	  "shard":null}
[junit4:junit4]   2> 67341 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67341 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67341 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67341 T366 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67341 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67341 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67341 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67465 T266 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 67466 T266 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:41871/solr shardId:shard2
[junit4:junit4]   2> 67468 T266 oasc.ZkController.register We are http://127.0.0.1:41871/solr/collection1/ and leader is http://127.0.0.1:47691/solr/collection1/
[junit4:junit4]   2> 67469 T266 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:41871/solr
[junit4:junit4]   2> 67469 T266 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 67469 T266 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C33 name=collection1 org.apache.solr.core.SolrCore@666cd931 url=http://127.0.0.1:41871/solr/collection1 node=127.0.0.1:41871_solr
[junit4:junit4]   2> 67470 T368 C33 P41871 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 67470 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 67470 T368 C33 P41871 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 67471 T368 C33 P41871 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 67471 T266 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   2> 67471 T368 C33 P41871 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:47691/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 67471 T266 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 67472 T368 C33 P41871 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:41871/solr START replicas=[http://127.0.0.1:47691/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 67472 T266 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 67472 T368 C33 P41871 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 67473 T368 C33 P41871 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 67473 T368 C33 P41871 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 67473 T368 C33 P41871 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 67475 T311 C32 P47691 REQ /get {getVersions=100&qt=/get&distrib=false&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 67477 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 67478 T266 oasc.AbstractFullDistribZkTestBase.waitForThingsToLevelOut Wait for recoveries to finish - wait 30 for each attempt
[junit4:junit4]   2> 67478 T266 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):30
[junit4:junit4]   2> 67479 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 67845 T287 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 67845 T287 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:41871/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:41871_solr",
[junit4:junit4]   2> 	  "shard":"shard2"}
[junit4:junit4]   2> 67848 T314 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67848 T326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67848 T302 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67848 T291 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67848 T286 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67848 T340 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 67848 T366 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (7)
[junit4:junit4]   2> 68480 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 69481 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C33_STATE=coll:collection1 core:collection1 props:{roles=null, core=collection1, collection=collection1, state=recovering, base_url=http://127.0.0.1:41871/solr, node_name=127.0.0.1:41871_solr, shard=shard2}
[junit4:junit4]   2> 70478 T368 C33 P41871 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 70478 T368 C33 P41871 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 70478 T368 C33 P41871 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:47691/solr/collection1/. core=collection1
[junit4:junit4]   2> 70478 T368 C33 P41871 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 70482 T310 C32 P47691 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 70485 T310 C32 P47691 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./org.apache.solr.client.solrj.impl.CloudSolrServerTest-1351459649251/jetty2
[junit4:junit4]   2> 70485 T266 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 70486 T310 C32 P47691 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 70486 T310 C32 P47691 /update {commit=true&commit_end_point=true&waitSearcher=true&openSearcher=false&softCommit=false&version=2&wt=javabin} {commit=} 0 4
[junit4:junit4]   2> 70487 T368 C33 P41871 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 70487 T368 C33 P41871 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 70491 T309 C32 P47691 REQ /replication {command=indexversion&qt=/replication&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 70492 T368 C33 

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

l.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 105025 T266 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 226040 T266 oas.SolrTestCaseJ4.endTrackingSearchers SEVERE ERROR: SolrIndexSearcher opens=12 closes=11
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=de_DE, timezone=America/Rainy_River
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=67262736,total=126418944
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestLBHttpSolrServer, FieldAnalysisResponseTest, ClientUtilsTest, MergeIndexesEmbeddedTest, TestHash, DocumentAnalysisResponseTest, TestFastInputStream, TestEmbeddedSolrServer, TestJavaBinCodec, TestSolrProperties, TestDocumentObjectBinder, SolrExampleJettyTest, SolrExampleEmbeddedTest, MultiCoreExampleJettyTest, LargeVolumeJettyTest, TermsResponseTest, SolrQueryTest, QueryResponseTest, SolrDocumentTest, ModifiableSolrParamsTest, TestXMLEscaping, CloudSolrServerTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=CloudSolrServerTest -Dtests.seed=5210640E2051991 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de_DE -Dtests.timezone=America/Rainy_River -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   0.00s J0 | CloudSolrServerTest (suite) <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=12 closes=11
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([5210640E2051991]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:243)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:97)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] Completed on J0 in 226.08s, 1 test, 2 failures <<< FAILURES!

[...truncated 11 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:185: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1159: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:827: There were test failures: 43 suites, 239 tests, 1 suite-level error, 1 failure

Total time: 28 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.8.0-ea-b58 -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message