lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b65) - Build # 3185 - Still Failing!
Date Wed, 12 Dec 2012 16:13:33 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/3185/
Java: 32bit/jdk1.8.0-ea-b65 -client -XX:+UseG1GC

3 tests failed.
FAILED:  org.apache.solr.cloud.SyncSliceTest.testDistribSearch

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

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 540 seconds
	at __randomizedtesting.SeedInfo.seed([A08AAC0EF28723FC:216C221685D843C0]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:165)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:115)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:110)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:563)
	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:224)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:697)
	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:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	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:46)
	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)


FAILED:  org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch

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

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 540 seconds
	at __randomizedtesting.SeedInfo.seed([A08AAC0EF28723FC:216C221685D843C0]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:165)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:115)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:110)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:563)
	at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSomeDocsAndRecover(BasicDistributedZk2Test.java:321)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:83)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:697)
	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:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	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:46)
	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)


FAILED:  org.apache.solr.cloud.BasicDistributedZkTest.testDistribSearch

Error Message:
Server at http://127.0.0.1:34554/solr returned non ok status:500, message:Server Error

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:34554/solr returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([A08AAC0EF28723FC:216C221685D843C0]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:374)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.CoreAdminRequest.process(CoreAdminRequest.java:429)
	at org.apache.solr.client.solrj.request.CoreAdminRequest.getStatus(CoreAdminRequest.java:472)
	at org.apache.solr.cloud.BasicDistributedZkTest.collectStartTimes(BasicDistributedZkTest.java:959)
	at org.apache.solr.cloud.BasicDistributedZkTest.testCollectionsAPI(BasicDistributedZkTest.java:795)
	at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:343)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:697)
	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:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	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:46)
	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 8671 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 2 T322 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZkTest-1355327705731
[junit4:junit4]   2> 5 T322 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 7 T323 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 26 T323 oaz.Environment.logEnv Server environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4]   2> 26 T323 oaz.Environment.logEnv Server environment:host.name=serv1
[junit4:junit4]   2> 26 T323 oaz.Environment.logEnv Server environment:java.version=1.8.0-ea
[junit4:junit4]   2> 26 T323 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 27 T323 oaz.Environment.logEnv Server environment:java.home=/mnt/ssd/jenkins/tools/java/32bit/jdk1.8.0-ea-b65/jre
[junit4:junit4]   2> 27 T323 oaz.Environment.logEnv Server environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/common/lucene-analyzers-common-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/highlighter/lucene-highlighter-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/memory/lucene-memory-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/misc/lucene-misc-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/spatial/lucene-spatial-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/suggest/lucene-suggest-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/grouping/lucene-grouping-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/queries/lucene-queries-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/queryparser/lucene-queryparser-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/cglib-nodep-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/easymock-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/guava-13.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/metrics-core-2.1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/objenesis-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/zookeeper-3.4.5.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-continuation-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-deploy-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-http-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-jmx-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-security-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-servlet-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-util-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-webapp-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-xml-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.5.jar:/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/32bit/jdk1.8.0-ea-b65/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.5.jar
[junit4:junit4]   2> 28 T323 oaz.Environment.logEnv Server environment:java.library.path=/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/i386:/lib:/usr/lib
[junit4:junit4]   2> 28 T323 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 28 T323 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4]   2> 28 T323 oaz.Environment.logEnv Server environment:os.name=Linux
[junit4:junit4]   2> 28 T323 oaz.Environment.logEnv Server environment:os.arch=i386
[junit4:junit4]   2> 28 T323 oaz.Environment.logEnv Server environment:os.version=3.2.0-34-generic
[junit4:junit4]   2> 28 T323 oaz.Environment.logEnv Server environment:user.name=jenkins
[junit4:junit4]   2> 28 T323 oaz.Environment.logEnv Server environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 29 T323 oaz.Environment.logEnv Server environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 40 T323 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 40 T323 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 40 T323 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 42 T323 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 66 T323 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 107 T322 oasc.ZkTestServer.run start zk server on port:44827
[junit4:junit4]   2> 135 T322 oaz.Environment.logEnv Client environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4]   2> 135 T322 oaz.Environment.logEnv Client environment:host.name=serv1
[junit4:junit4]   2> 136 T322 oaz.Environment.logEnv Client environment:java.version=1.8.0-ea
[junit4:junit4]   2> 136 T322 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 137 T322 oaz.Environment.logEnv Client environment:java.home=/mnt/ssd/jenkins/tools/java/32bit/jdk1.8.0-ea-b65/jre
[junit4:junit4]   2> 137 T322 oaz.Environment.logEnv Client environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/common/lucene-analyzers-common-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/highlighter/lucene-highlighter-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/memory/lucene-memory-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/misc/lucene-misc-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/spatial/lucene-spatial-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/suggest/lucene-suggest-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/grouping/lucene-grouping-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/queries/lucene-queries-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/queryparser/lucene-queryparser-4.1-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/cglib-nodep-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/easymock-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/guava-13.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/metrics-core-2.1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/objenesis-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/solrj/lib/zookeeper-3.4.5.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-continuation-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-deploy-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-http-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-jmx-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-security-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-servlet-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-util-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-webapp-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/jetty-xml-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.5.jar:/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/32bit/jdk1.8.0-ea-b65/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.5.jar
[junit4:junit4]   2> 140 T322 oaz.Environment.logEnv Client environment:java.library.path=/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/i386:/lib:/usr/lib
[junit4:junit4]   2> 141 T322 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 141 T322 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4]   2> 142 T322 oaz.Environment.logEnv Client environment:os.name=Linux
[junit4:junit4]   2> 143 T322 oaz.Environment.logEnv Client environment:os.arch=i386
[junit4:junit4]   2> 143 T322 oaz.Environment.logEnv Client environment:os.version=3.2.0-34-generic
[junit4:junit4]   2> 144 T322 oaz.Environment.logEnv Client environment:user.name=jenkins
[junit4:junit4]   2> 145 T322 oaz.Environment.logEnv Client environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 145 T322 oaz.Environment.logEnv Client environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 149 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7a6027
[junit4:junit4]   2> 173 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 179 T328 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 181 T328 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 181 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41574
[junit4:junit4]   2> 191 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41574
[junit4:junit4]   2> 197 T326 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 228 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0000 with negotiated timeout 10000 for client /127.0.0.1:41574
[junit4:junit4]   2> 228 T328 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0000, negotiated timeout = 10000
[junit4:junit4]   2> 234 T329 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a6027 name:ZooKeeperConnection Watcher:127.0.0.1:44827 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 235 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 238 T322 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 274 T327 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b8fd392cb0000
[junit4:junit4]   2> 275 T329 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 275 T322 oaz.ZooKeeper.close Session: 0x13b8fd392cb0000 closed
[junit4:junit4]   2> 276 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c62ae4
[junit4:junit4]   2> 277 T324 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:41574 which had sessionid 0x13b8fd392cb0000
[junit4:junit4]   2> 278 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 278 T330 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 279 T330 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 279 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41575
[junit4:junit4]   2> 279 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41575
[junit4:junit4]   2> 280 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0001 with negotiated timeout 10000 for client /127.0.0.1:41575
[junit4:junit4]   2> 280 T330 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0001, negotiated timeout = 10000
[junit4:junit4]   2> 281 T331 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c62ae4 name:ZooKeeperConnection Watcher:127.0.0.1:44827/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 281 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 285 T322 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 289 T322 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 293 T322 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 295 T322 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 299 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 300 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 306 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 306 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 310 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 311 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 317 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 318 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 321 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 322 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 324 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 325 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 328 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 328 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 331 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 331 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 334 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 335 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 338 T322 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 339 T322 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 342 T327 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b8fd392cb0001
[junit4:junit4]   2> 343 T331 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 343 T322 oaz.ZooKeeper.close Session: 0x13b8fd392cb0001 closed
[junit4:junit4]   2> 343 T324 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:41575 which had sessionid 0x13b8fd392cb0001
[junit4:junit4]   2> 404 T322 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 406 T322 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:42848
[junit4:junit4]   2> 407 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 407 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071
[junit4:junit4]   2> 407 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/'
[junit4:junit4]   2> 421 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 421 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 421 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071
[junit4:junit4]   2> 422 T322 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/solr.xml
[junit4:junit4]   2> 422 T322 oasc.CoreContainer.<init> New CoreContainer 26407394
[junit4:junit4]   2> 423 T322 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/'
[junit4:junit4]   2> 423 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/'
[junit4:junit4]   2> 446 T322 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 457 T322 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44827/solr
[junit4:junit4]   2> 465 T322 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 465 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@3babc7
[junit4:junit4]   2> 466 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 466 T341 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 467 T341 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 467 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41576
[junit4:junit4]   2> 467 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41576
[junit4:junit4]   2> 469 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0002 with negotiated timeout 20000 for client /127.0.0.1:41576
[junit4:junit4]   2> 469 T341 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0002, negotiated timeout = 20000
[junit4:junit4]   2> 469 T342 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3babc7 name:ZooKeeperConnection Watcher:127.0.0.1:44827 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 469 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 471 T327 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b8fd392cb0002
[junit4:junit4]   2> 472 T324 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:41576 which had sessionid 0x13b8fd392cb0002
[junit4:junit4]   2> 472 T342 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 472 T322 oaz.ZooKeeper.close Session: 0x13b8fd392cb0002 closed
[junit4:junit4]   2> 473 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@3754e9
[junit4:junit4]   2> 474 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 475 T343 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 475 T343 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 475 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41577
[junit4:junit4]   2> 476 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41577
[junit4:junit4]   2> 476 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0003 with negotiated timeout 8000 for client /127.0.0.1:41577
[junit4:junit4]   2> 476 T343 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0003, negotiated timeout = 8000
[junit4:junit4]   2> 477 T344 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3754e9 name:ZooKeeperConnection Watcher:127.0.0.1:44827/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 477 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 485 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x2 zxid:0x1b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 489 T322 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 491 T322 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42848_solr
[junit4:junit4]   2> 493 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:delete cxid:0x7 zxid:0x1d txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42848_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42848_solr
[junit4:junit4]   2> 496 T322 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42848_solr
[junit4:junit4]   2> 498 T322 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 499 T322 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 499 T322 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 499 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 500 T322 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 500 T322 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 500 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 501 T322 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 501 T322 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 501 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 505 T322 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 516 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:delete cxid:0x15 zxid:0x22 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 517 T322 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 520 T322 oasc.Overseer.start Overseer (id=88822756527505411-127.0.0.1:42848_solr-n_0000000000) starting
[junit4:junit4]   2> 520 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x1a zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 525 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x1b zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 526 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x1c zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 529 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x1d zxid:0x27 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 530 T346 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 531 T322 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 533 T322 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 543 T345 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 552 T347 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/collection1
[junit4:junit4]   2> 552 T347 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 553 T347 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 553 T347 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 555 T347 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/collection1/'
[junit4:junit4]   2> 556 T347 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/collection1/lib/README' to classloader
[junit4:junit4]   2> 556 T347 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 591 T347 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 644 T347 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 645 T347 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 649 T347 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1099 T347 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1106 T347 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1109 T347 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1122 T347 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1143 T347 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1147 T347 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1148 T347 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1149 T347 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1355327706071/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/control/data/
[junit4:junit4]   2> 1151 T347 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1227bc5
[junit4:junit4]   2> 1151 T347 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/control/data/index/
[junit4:junit4]   2> 1152 T347 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1152 T347 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/control/data/index forceNew:false
[junit4:junit4]   2> 1156 T347 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@16d9ace; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1156 T347 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1157 T347 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1158 T347 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1158 T347 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1158 T347 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1158 T347 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1159 T347 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1159 T347 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1159 T347 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1160 T347 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1160 T347 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1161 T347 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1162 T347 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1162 T347 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1162 T347 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1163 T347 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1163 T347 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1163 T347 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1164 T347 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1165 T347 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1165 T347 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1166 T347 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1166 T347 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1167 T347 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1168 T347 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1169 T347 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1170 T347 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1172 T347 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1174 T347 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1175 T347 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [STOPWORDS.TXT, SCHEMA.XML, OLD_SYNONYMS.TXT, OPEN-EXCHANGE-RATES.JSON, PROTWORDS.TXT, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, CURRENCY.XML]
[junit4:junit4]   2> 1177 T347 oass.SolrIndexSearcher.<init> Opening Searcher@629a98 main
[junit4:junit4]   2> 1178 T347 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 1186 T347 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1187 T347 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1187 T347 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1190 T347 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1213 T348 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@629a98 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1217 T347 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 1217 T347 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1218 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x53 zxid:0x2a txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1221 T347 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1221 T347 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:42848/solr shardId:control_shard
[junit4:junit4]   2> 1225 T347 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 1229 T347 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1236 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:delete cxid:0x62 zxid:0x31 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1236 T347 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1237 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x63 zxid:0x32 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1551 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1553 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42848/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42848_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 1558 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x6a zxid:0x34 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1570 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1739 T347 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1740 T347 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1740 T347 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:42848/solr/collection1/
[junit4:junit4]   2> 1740 T347 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1741 T347 oasc.SyncStrategy.syncToMe http://127.0.0.1:42848/solr/collection1/ has no replicas
[junit4:junit4]   2> 1741 T347 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42848/solr/collection1/
[junit4:junit4]   2> 1741 T347 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1746 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0003 type:create cxid:0x89 zxid:0x3f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2077 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2082 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2099 T347 oasc.ZkController.register We are http://127.0.0.1:42848/solr/collection1/ and leader is http://127.0.0.1:42848/solr/collection1/
[junit4:junit4]   2> 2100 T347 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42848/solr
[junit4:junit4]   2> 2100 T347 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2100 T347 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 2101 T347 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2102 T347 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2103 T322 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 2104 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2104 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2164 T322 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 2166 T322 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:40244
[junit4:junit4]   2> 2167 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2168 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835
[junit4:junit4]   2> 2168 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835/'
[junit4:junit4]   2> 2191 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2192 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2193 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835
[junit4:junit4]   2> 2194 T322 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835/solr.xml
[junit4:junit4]   2> 2194 T322 oasc.CoreContainer.<init> New CoreContainer 12061925
[junit4:junit4]   2> 2195 T322 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835/'
[junit4:junit4]   2> 2196 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835/'
[junit4:junit4]   2> 2213 T322 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2224 T322 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44827/solr
[junit4:junit4]   2> 2224 T322 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 2225 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@d29a7a
[junit4:junit4]   2> 2225 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2226 T358 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 2226 T358 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 2226 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41578
[junit4:junit4]   2> 2227 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41578
[junit4:junit4]   2> 2228 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0004 with negotiated timeout 20000 for client /127.0.0.1:41578
[junit4:junit4]   2> 2228 T358 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0004, negotiated timeout = 20000
[junit4:junit4]   2> 2228 T359 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d29a7a name:ZooKeeperConnection Watcher:127.0.0.1:44827 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2228 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2230 T327 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b8fd392cb0004
[junit4:junit4]   2> 2230 T324 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:41578 which had sessionid 0x13b8fd392cb0004
[junit4:junit4]   2> 2230 T322 oaz.ZooKeeper.close Session: 0x13b8fd392cb0004 closed
[junit4:junit4]   2> 2230 T359 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 2231 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@d182d9
[junit4:junit4]   2> 2232 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2232 T360 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 2232 T360 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 2232 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41579
[junit4:junit4]   2> 2233 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41579
[junit4:junit4]   2> 2233 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0005 with negotiated timeout 8000 for client /127.0.0.1:41579
[junit4:junit4]   2> 2233 T360 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0005, negotiated timeout = 8000
[junit4:junit4]   2> 2234 T361 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d182d9 name:ZooKeeperConnection Watcher:127.0.0.1:44827/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2234 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2235 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0005 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2236 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0005 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2237 T322 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40244_solr
[junit4:junit4]   2> 2238 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0005 type:delete cxid:0x4 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:40244_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:40244_solr
[junit4:junit4]   2> 2238 T322 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:40244_solr
[junit4:junit4]   2> 2241 T344 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2241 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2241 T322 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2242 T322 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2242 T322 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2243 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2243 T322 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2243 T322 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2244 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2244 T322 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2245 T322 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2245 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2274 T322 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2280 T362 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835/collection1
[junit4:junit4]   2> 2280 T362 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2281 T362 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2281 T362 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2282 T362 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835/collection1/'
[junit4:junit4]   2> 2283 T362 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty1-1355327707835/collection1/lib/README' to classloader
[junit4:junit4]   2> 2283 T362 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty1-1355327707835/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2314 T362 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 2368 T362 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2369 T362 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2374 T362 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2589 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2590 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42848/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42848_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 2594 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2594 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2853 T362 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2859 T362 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2861 T362 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2881 T362 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2889 T362 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2898 T362 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2900 T362 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2903 T362 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1355327707835/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty1/
[junit4:junit4]   2> 2903 T362 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1227bc5
[junit4:junit4]   2> 2904 T362 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty1/index/
[junit4:junit4]   2> 2905 T362 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2906 T362 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty1/index forceNew:false
[junit4:junit4]   2> 2911 T362 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f8c64f; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2911 T362 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2912 T362 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2915 T362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2915 T362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2915 T362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2916 T362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2917 T362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2917 T362 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2918 T362 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2919 T362 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2920 T362 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2920 T362 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2921 T362 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2921 T362 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2922 T362 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2922 T362 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2922 T362 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2923 T362 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2924 T362 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2924 T362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2925 T362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2926 T362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2927 T362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2927 T362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2928 T362 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2929 T362 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2929 T362 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2932 T362 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2933 T362 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2935 T362 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [STOPWORDS.TXT, SCHEMA.XML, OLD_SYNONYMS.TXT, OPEN-EXCHANGE-RATES.JSON, PROTWORDS.TXT, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, CURRENCY.XML]
[junit4:junit4]   2> 2937 T362 oass.SolrIndexSearcher.<init> Opening Searcher@1363e3 main
[junit4:junit4]   2> 2937 T362 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 2938 T362 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2938 T362 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2938 T362 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2941 T362 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2964 T363 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1363e3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2979 T362 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 2979 T362 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3099 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3100 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40244/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40244_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 3100 T345 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 3102 T345 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 3107 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3107 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3482 T362 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3482 T362 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:40244/solr shardId:shard1
[junit4:junit4]   2> 3483 T362 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3489 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0005 type:delete cxid:0x45 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3490 T362 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3490 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0005 type:create cxid:0x46 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3492 T362 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3492 T362 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3493 T362 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:40244/solr/collection1/
[junit4:junit4]   2> 3493 T362 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3493 T362 oasc.SyncStrategy.syncToMe http://127.0.0.1:40244/solr/collection1/ has no replicas
[junit4:junit4]   2> 3493 T362 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:40244/solr/collection1/
[junit4:junit4]   2> 3493 T362 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3497 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0005 type:create cxid:0x50 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3614 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3631 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3631 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3649 T362 oasc.ZkController.register We are http://127.0.0.1:40244/solr/collection1/ and leader is http://127.0.0.1:40244/solr/collection1/
[junit4:junit4]   2> 3650 T362 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:40244/solr
[junit4:junit4]   2> 3650 T362 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3651 T362 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3651 T362 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3654 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3656 T322 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3657 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3658 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3729 T322 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3731 T322 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:34554
[junit4:junit4]   2> 3732 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3733 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395
[junit4:junit4]   2> 3733 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395/'
[junit4:junit4]   2> 3746 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3746 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3747 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395
[junit4:junit4]   2> 3747 T322 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395/solr.xml
[junit4:junit4]   2> 3747 T322 oasc.CoreContainer.<init> New CoreContainer 28039911
[junit4:junit4]   2> 3748 T322 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395/'
[junit4:junit4]   2> 3748 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395/'
[junit4:junit4]   2> 3762 T322 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3772 T322 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44827/solr
[junit4:junit4]   2> 3773 T322 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3773 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@9e2e78
[junit4:junit4]   2> 3774 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3774 T373 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3775 T373 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 3775 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41580
[junit4:junit4]   2> 3775 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41580
[junit4:junit4]   2> 3776 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0006 with negotiated timeout 20000 for client /127.0.0.1:41580
[junit4:junit4]   2> 3776 T373 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0006, negotiated timeout = 20000
[junit4:junit4]   2> 3777 T374 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9e2e78 name:ZooKeeperConnection Watcher:127.0.0.1:44827 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3777 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3778 T327 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b8fd392cb0006
[junit4:junit4]   2> 3779 T324 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:41580 which had sessionid 0x13b8fd392cb0006
[junit4:junit4]   2> 3779 T374 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3779 T322 oaz.ZooKeeper.close Session: 0x13b8fd392cb0006 closed
[junit4:junit4]   2> 3780 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@16da65f
[junit4:junit4]   2> 3781 T375 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3781 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3781 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41581
[junit4:junit4]   2> 3781 T375 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 3782 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41581
[junit4:junit4]   2> 3782 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0007 with negotiated timeout 8000 for client /127.0.0.1:41581
[junit4:junit4]   2> 3782 T375 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0007, negotiated timeout = 8000
[junit4:junit4]   2> 3783 T376 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16da65f name:ZooKeeperConnection Watcher:127.0.0.1:44827/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3783 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3784 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0007 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3785 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0007 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3786 T322 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34554_solr
[junit4:junit4]   2> 3787 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0007 type:delete cxid:0x4 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34554_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34554_solr
[junit4:junit4]   2> 3787 T322 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34554_solr
[junit4:junit4]   2> 3790 T344 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3790 T361 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3790 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3790 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3791 T322 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3791 T322 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3792 T322 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3792 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3792 T322 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3793 T322 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3793 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3794 T322 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3794 T322 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3794 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3800 T322 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3806 T377 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395/collection1
[junit4:junit4]   2> 3806 T377 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3807 T377 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3807 T377 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3808 T377 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395/collection1/'
[junit4:junit4]   2> 3809 T377 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty2-1355327709395/collection1/lib/README' to classloader
[junit4:junit4]   2> 3809 T377 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty2-1355327709395/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3845 T377 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 3901 T377 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3903 T377 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3907 T377 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4141 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4141 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40244/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40244_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 4145 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4145 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4146 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4326 T377 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4332 T377 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4335 T377 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4349 T377 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4353 T377 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4356 T377 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4358 T377 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4359 T377 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1355327709395/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty2/
[junit4:junit4]   2> 4360 T377 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1227bc5
[junit4:junit4]   2> 4360 T377 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty2/index/
[junit4:junit4]   2> 4361 T377 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4362 T377 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty2/index forceNew:false
[junit4:junit4]   2> 4367 T377 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@16db53a; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4368 T377 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4368 T377 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4371 T377 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4371 T377 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4371 T377 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4372 T377 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4373 T377 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4373 T377 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4374 T377 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4375 T377 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4377 T377 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4378 T377 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4379 T377 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4379 T377 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4380 T377 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4380 T377 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4380 T377 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4381 T377 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4381 T377 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4382 T377 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4383 T377 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4383 T377 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4384 T377 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4385 T377 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4385 T377 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4386 T377 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4387 T377 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4389 T377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4391 T377 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4393 T377 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [STOPWORDS.TXT, SCHEMA.XML, OLD_SYNONYMS.TXT, OPEN-EXCHANGE-RATES.JSON, PROTWORDS.TXT, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, CURRENCY.XML]
[junit4:junit4]   2> 4396 T377 oass.SolrIndexSearcher.<init> Opening Searcher@1dc0066 main
[junit4:junit4]   2> 4396 T377 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 4397 T377 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4397 T377 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4398 T377 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4401 T377 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4436 T378 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dc0066 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4446 T377 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 4446 T377 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4651 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4652 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34554/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34554_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 4652 T345 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 4652 T345 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 4756 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4756 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4756 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4948 T377 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4948 T377 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:34554/solr shardId:shard2
[junit4:junit4]   2> 4949 T377 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 4955 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0007 type:delete cxid:0x44 zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 4956 T377 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4956 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0007 type:create cxid:0x45 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4958 T377 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4958 T377 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4958 T377 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:34554/solr/collection1/
[junit4:junit4]   2> 4959 T377 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4959 T377 oasc.SyncStrategy.syncToMe http://127.0.0.1:34554/solr/collection1/ has no replicas
[junit4:junit4]   2> 4959 T377 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34554/solr/collection1/
[junit4:junit4]   2> 4959 T377 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 4962 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0007 type:create cxid:0x4e zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5263 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5271 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5271 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5271 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5315 T377 oasc.ZkController.register We are http://127.0.0.1:34554/solr/collection1/ and leader is http://127.0.0.1:34554/solr/collection1/
[junit4:junit4]   2> 5316 T377 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34554/solr
[junit4:junit4]   2> 5316 T377 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5316 T377 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 5316 T377 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5318 T377 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5319 T322 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5319 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5320 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5381 T322 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 5383 T322 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:50073
[junit4:junit4]   2> 5384 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5384 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051
[junit4:junit4]   2> 5384 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051/'
[junit4:junit4]   2> 5397 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5398 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5398 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051
[junit4:junit4]   2> 5399 T322 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051/solr.xml
[junit4:junit4]   2> 5399 T322 oasc.CoreContainer.<init> New CoreContainer 18276485
[junit4:junit4]   2> 5399 T322 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051/'
[junit4:junit4]   2> 5400 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051/'
[junit4:junit4]   2> 5423 T322 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5434 T322 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44827/solr
[junit4:junit4]   2> 5434 T322 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 5434 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@135f53d
[junit4:junit4]   2> 5435 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5436 T388 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 5436 T388 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 5436 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41582
[junit4:junit4]   2> 5437 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41582
[junit4:junit4]   2> 5437 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0008 with negotiated timeout 20000 for client /127.0.0.1:41582
[junit4:junit4]   2> 5437 T388 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0008, negotiated timeout = 20000
[junit4:junit4]   2> 5438 T389 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@135f53d name:ZooKeeperConnection Watcher:127.0.0.1:44827 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5438 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5439 T327 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b8fd392cb0008
[junit4:junit4]   2> 5440 T324 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:41582 which had sessionid 0x13b8fd392cb0008
[junit4:junit4]   2> 5440 T389 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 5440 T322 oaz.ZooKeeper.close Session: 0x13b8fd392cb0008 closed
[junit4:junit4]   2> 5441 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ad229b
[junit4:junit4]   2> 5442 T390 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 5442 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5442 T390 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 5442 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41583
[junit4:junit4]   2> 5443 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41583
[junit4:junit4]   2> 5443 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb0009 with negotiated timeout 8000 for client /127.0.0.1:41583
[junit4:junit4]   2> 5443 T390 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb0009, negotiated timeout = 8000
[junit4:junit4]   2> 5444 T391 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ad229b name:ZooKeeperConnection Watcher:127.0.0.1:44827/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5444 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5445 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0009 type:create cxid:0x1 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5446 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0009 type:create cxid:0x2 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5447 T322 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50073_solr
[junit4:junit4]   2> 5447 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb0009 type:delete cxid:0x4 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50073_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50073_solr
[junit4:junit4]   2> 5448 T322 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50073_solr
[junit4:junit4]   2> 5450 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5451 T344 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5451 T361 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5451 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5451 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5451 T322 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5452 T376 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5452 T322 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5452 T322 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5453 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5453 T322 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5453 T322 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5454 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5454 T322 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5454 T322 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5455 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5461 T322 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5468 T392 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051/collection1
[junit4:junit4]   2> 5468 T392 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5469 T392 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5469 T392 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5470 T392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051/collection1/'
[junit4:junit4]   2> 5470 T392 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty3-1355327711051/collection1/lib/README' to classloader
[junit4:junit4]   2> 5471 T392 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty3-1355327711051/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5503 T392 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 5560 T392 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5561 T392 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5565 T392 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5776 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5777 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34554/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34554_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5781 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5781 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5781 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5781 T391 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6009 T392 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6015 T392 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6018 T392 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6030 T392 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6033 T392 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6038 T392 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6045 T392 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6047 T392 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty3-1355327711051/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/
[junit4:junit4]   2> 6047 T392 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1227bc5
[junit4:junit4]   2> 6048 T392 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index/
[junit4:junit4]   2> 6048 T392 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6049 T392 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index forceNew:false
[junit4:junit4]   2> 6128 T392 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1abd096; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6128 T392 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6128 T392 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6130 T392 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6130 T392 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6130 T392 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6130 T392 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6131 T392 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6131 T392 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6131 T392 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6132 T392 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6133 T392 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6133 T392 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6134 T392 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6135 T392 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6135 T392 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6135 T392 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6136 T392 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6137 T392 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6137 T392 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6138 T392 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6139 T392 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6139 T392 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6140 T392 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6141 T392 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6141 T392 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6142 T392 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6143 T392 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6145 T392 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6147 T392 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6148 T392 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [STOPWORDS.TXT, SCHEMA.XML, OLD_SYNONYMS.TXT, OPEN-EXCHANGE-RATES.JSON, PROTWORDS.TXT, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, CURRENCY.XML]
[junit4:junit4]   2> 6150 T392 oass.SolrIndexSearcher.<init> Opening Searcher@28e2f1 main
[junit4:junit4]   2> 6151 T392 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 6151 T392 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6151 T392 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6152 T392 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6155 T392 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6178 T393 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28e2f1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6191 T392 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 6191 T392 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6286 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6287 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50073/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50073_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 6288 T345 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 6288 T345 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 6299 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6299 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6299 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6299 T391 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6693 T392 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6693 T392 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:50073/solr shardId:shard1
[junit4:junit4]   2> 6699 T392 oasc.ZkController.register We are http://127.0.0.1:50073/solr/collection1/ and leader is http://127.0.0.1:40244/solr/collection1/
[junit4:junit4]   2> 6699 T392 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50073/solr
[junit4:junit4]   2> 6699 T392 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 6700 T392 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C24 name=collection1 org.apache.solr.core.SolrCore@10cd13f url=http://127.0.0.1:50073/solr/collection1 node=127.0.0.1:50073_solr
[junit4:junit4]   2> 6705 T394 C24 P50073 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 6706 T392 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6707 T394 C24 P50073 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 6707 T394 C24 P50073 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 6708 T394 C24 P50073 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6709 T322 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 6710 T394 C24 P50073 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:40244/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 6710 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6711 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6716 T394 C24 P50073 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 6723 T394 C24 P50073 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:50073/solr START replicas=[http://127.0.0.1:40244/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 6723 T394 C24 P50073 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 6724 T394 C24 P50073 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 6724 T394 C24 P50073 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 6724 T394 C24 P50073 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C25 name=collection1 org.apache.solr.core.SolrCore@f3a603 url=http://127.0.0.1:40244/solr/collection1 node=127.0.0.1:40244_solr
[junit4:junit4]   2> 6729 T356 C25 P40244 REQ /get {qt=/get&getVersions=100&distrib=false&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6801 T322 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 6805 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6805 T322 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:60485
[junit4:junit4]   2> 6805 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6806 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50073/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50073_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 6806 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446
[junit4:junit4]   2> 6807 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446/'
[junit4:junit4]   2> 6809 T391 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6809 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6810 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6810 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6825 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6825 T322 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6826 T322 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446
[junit4:junit4]   2> 6827 T322 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446/solr.xml
[junit4:junit4]   2> 6827 T322 oasc.CoreContainer.<init> New CoreContainer 28273754
[junit4:junit4]   2> 6828 T322 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446/'
[junit4:junit4]   2> 6828 T322 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446/'
[junit4:junit4]   2> 6845 T322 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6858 T322 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44827/solr
[junit4:junit4]   2> 6858 T322 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 6859 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@e4e95a
[junit4:junit4]   2> 6860 T405 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 6860 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6861 T405 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 6861 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41586
[junit4:junit4]   2> 6861 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41586
[junit4:junit4]   2> 6862 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb000a with negotiated timeout 20000 for client /127.0.0.1:41586
[junit4:junit4]   2> 6862 T405 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb000a, negotiated timeout = 20000
[junit4:junit4]   2> 6863 T406 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e4e95a name:ZooKeeperConnection Watcher:127.0.0.1:44827 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6863 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6864 T327 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b8fd392cb000a
[junit4:junit4]   2> 6865 T406 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 6865 T324 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:41586 which had sessionid 0x13b8fd392cb000a
[junit4:junit4]   2> 6865 T322 oaz.ZooKeeper.close Session: 0x13b8fd392cb000a closed
[junit4:junit4]   2> 6866 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@168de97
[junit4:junit4]   2> 6867 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6867 T407 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 6867 T407 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 6868 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41587
[junit4:junit4]   2> 6868 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41587
[junit4:junit4]   2> 6870 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb000b with negotiated timeout 8000 for client /127.0.0.1:41587
[junit4:junit4]   2> 6870 T407 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb000b, negotiated timeout = 8000
[junit4:junit4]   2> 6870 T408 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@168de97 name:ZooKeeperConnection Watcher:127.0.0.1:44827/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6871 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6872 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb000b type:create cxid:0x1 zxid:0xa6 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6873 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb000b type:create cxid:0x2 zxid:0xa7 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6874 T322 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60485_solr
[junit4:junit4]   2> 6874 T327 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b8fd392cb000b type:delete cxid:0x4 zxid:0xa8 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60485_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60485_solr
[junit4:junit4]   2> 6875 T322 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60485_solr
[junit4:junit4]   2> 6877 T391 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6877 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6877 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6877 T361 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6878 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 6878 T391 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6879 T376 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6879 T344 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6879 T322 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6880 T322 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6880 T322 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6881 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6882 T322 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6883 T322 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6883 T322 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6884 T322 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6885 T322 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6886 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6898 T322 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6909 T409 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446/collection1
[junit4:junit4]   2> 6910 T409 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6911 T409 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6912 T409 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6914 T409 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446/collection1/'
[junit4:junit4]   2> 6915 T409 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty4-1355327712446/collection1/lib/README' to classloader
[junit4:junit4]   2> 6916 T409 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty4-1355327712446/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6998 T409 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 7045 T409 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 7046 T409 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7051 T409 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7513 T409 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7519 T409 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7522 T409 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7536 T409 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7540 T409 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7544 T409 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7545 T409 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7546 T409 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty4-1355327712446/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/
[junit4:junit4]   2> 7547 T409 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1227bc5
[junit4:junit4]   2> 7547 T409 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index/
[junit4:junit4]   2> 7547 T409 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7548 T409 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index forceNew:false
[junit4:junit4]   2> 7568 T409 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f6b220; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7568 T409 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7569 T409 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7570 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7570 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7570 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7571 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7571 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7571 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7572 T409 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7572 T409 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7573 T409 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7574 T409 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7574 T409 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7575 T409 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7575 T409 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7576 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7576 T409 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7576 T409 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7577 T409 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7578 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7578 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7579 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7580 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7581 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7581 T409 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7582 T409 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7583 T409 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7585 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7587 T409 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7588 T409 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [STOPWORDS.TXT, SCHEMA.XML, OLD_SYNONYMS.TXT, OPEN-EXCHANGE-RATES.JSON, PROTWORDS.TXT, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, CURRENCY.XML]
[junit4:junit4]   2> 7590 T409 oass.SolrIndexSearcher.<init> Opening Searcher@176cd2d main
[junit4:junit4]   2> 7591 T409 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 7591 T409 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7591 T409 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7592 T409 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7595 T409 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7619 T410 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@176cd2d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7626 T409 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 7627 T409 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7817 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7818 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60485/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60485_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 7818 T345 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 7819 T345 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 7825 T408 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7825 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7825 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7825 T391 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7825 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8128 T409 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8129 T409 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60485/solr shardId:shard2
[junit4:junit4]   2> 8133 T409 oasc.ZkController.register We are http://127.0.0.1:60485/solr/collection1/ and leader is http://127.0.0.1:34554/solr/collection1/
[junit4:junit4]   2> 8133 T409 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60485/solr
[junit4:junit4]   2> 8133 T409 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 8133 T409 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C26 name=collection1 org.apache.solr.core.SolrCore@1bcc78 url=http://127.0.0.1:60485/solr/collection1 node=127.0.0.1:60485_solr
[junit4:junit4]   2> 8134 T411 C26 P60485 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 8134 T411 C26 P60485 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 8134 T409 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8135 T411 C26 P60485 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 8135 T411 C26 P60485 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8136 T411 C26 P60485 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:34554/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 8136 T322 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 8136 T411 C26 P60485 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60485/solr START replicas=[http://127.0.0.1:34554/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 8137 T322 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8137 T411 C26 P60485 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 8138 T411 C26 P60485 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 8137 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8138 T411 C26 P60485 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 8139 T411 C26 P60485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8144 T322 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44827/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@16ae1da
[junit4:junit4]   2> ASYNC  NEW_CORE C27 name=collection1 org.apache.solr.core.SolrCore@176dced url=http://127.0.0.1:34554/solr/collection1 node=127.0.0.1:34554_solr
[junit4:junit4]   2> 8145 T369 C27 P34554 REQ /get {qt=/get&getVersions=100&distrib=false&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8146 T413 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:44827. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8146 T322 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8146 T413 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44827, initiating session
[junit4:junit4]   2> 8146 T324 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:41590
[junit4:junit4]   2> 8147 T324 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:41590
[junit4:junit4]   2> 8147 T326 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b8fd392cb000c with negotiated timeout 10000 for client /127.0.0.1:41590
[junit4:junit4]   2> 8147 T413 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:44827, sessionid = 0x13b8fd392cb000c, negotiated timeout = 10000
[junit4:junit4]   2> 8148 T414 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16ae1da name:ZooKeeperConnection Watcher:127.0.0.1:44827/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8148 T322 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8149 T322 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8153 T322 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 8154 T322 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 8158 T322 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8160 T322 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):540
[junit4:junit4]   2> 8161 T322 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8332 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8333 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60485/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60485_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 8395 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8395 T391 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8395 T408 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8395 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8396 T414 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8396 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9163 T322 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C24_STATE=coll:collection1 core:collection1 props:{collection=collection1, base_url=http://127.0.0.1:50073/solr, node_name=127.0.0.1:50073_solr, roles=null, shard=shard1, state=recovering, core=collection1}
[junit4:junit4]   2> 9730 T394 C24 P50073 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 9731 T394 C24 P50073 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 9732 T394 C24 P50073 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:40244/solr/collection1/. core=collection1
[junit4:junit4]   2> 9732 T394 C24 P50073 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9742 T356 C25 P40244 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 9764 T356 C25 P40244 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9768 T356 C25 P40244 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f8c64f; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9769 T356 C25 P40244 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 9896 T356 C25 P40244 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f8c64f; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f8c64f; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 9897 T356 C25 P40244 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 9897 T356 C25 P40244 oass.SolrIndexSearcher.<init> Opening Searcher@f17431 realtime
[junit4:junit4]   2> 9897 T356 C25 P40244 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 9898 T356 C25 P40244 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9898 T356 C25 P40244 /update {commit=true&openSearcher=false&version=2&softCommit=false&waitSearcher=true&commit_end_point=true&wt=javabin} {commit=} 0 159
[junit4:junit4]   2> 9914 T394 C24 P50073 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 9915 T394 C24 P50073 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 9920 T394 C24 P50073 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 9922 T357 C25 P40244 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 9923 T357 C25 P40244 REQ /replication {command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9923 T394 C24 P50073 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 9924 T394 C24 P50073 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 9924 T394 C24 P50073 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 9926 T356 C25 P40244 REQ /replication {command=filelist&qt=/replication&generation=2&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9926 T394 C24 P50073 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 9927 T394 C24 P50073 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655 fullCopy=true
[junit4:junit4]   2> 9942 T354 C25 P40244 REQ /replication {command=filecontent&file=segments_2&qt=/replication&checksum=true&generation=2&wt=filestream} status=0 QTime=2 
[junit4:junit4]   2> 9948 T394 C24 P50073 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 9956 T394 C24 P50073 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20121212105515655
[junit4:junit4]   2> 9957 T394 C24 P50073 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index/ new=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655
[junit4:junit4]   2> 9957 T394 C24 P50073 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655 forceNew:false
[junit4:junit4]   2> 9959 T394 C24 P50073 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7a5b2d; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 9960 T394 C24 P50073 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 9961 T394 C24 P50073 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 9961 T394 C24 P50073 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 9962 T394 C24 P50073 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 9962 T394 C24 P50073 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655
[junit4:junit4]   2> 9962 T394 C24 P50073 oash.SnapPuller$2.onClose removing old index directory ./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index
[junit4:junit4]   2> 9963 T394 C24 P50073 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655 forceNew:true
[junit4:junit4]   2> 9965 T394 C24 P50073 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655 lockFactory=org.apache.lucene.store.NativeFSLockFactory@15ae357; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 9965 T394 C24 P50073 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 9965 T394 C24 P50073 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 9966 T394 C24 P50073 oass.SolrIndexSearcher.<init> Opening Searcher@3b8150 main
[junit4:junit4]   2> 9966 T394 C24 P50073 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 9968 T393 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3b8150 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 9969 T394 C24 P50073 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9990 T394 C24 P50073 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655 lockFactory=org.apache.lucene.store.NativeFSLockFactory@15ae357; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty3/index.20121212105515655 lockFactory=org.apache.lucene.store.NativeFSLockFactory@15ae357; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 9991 T394 C24 P50073 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 9991 T394 C24 P50073 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9992 T394 C24 P50073 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 9992 T394 C24 P50073 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 9992 T394 C24 P50073 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 9992 T394 C24 P50073 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9993 T394 C24 P50073 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 10165 T322 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10405 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10405 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50073/solr",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50073_solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 10416 T344 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10416 T376 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10416 T408 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10416 T414 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10416 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10416 T391 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2>  C26_STATE=coll:collection1 core:collection1 props:{collection=collection1, base_url=http://127.0.0.1:60485/solr, node_name=127.0.0.1:60485_solr, roles=null, shard=shard2, state=recovering, core=collection1}
[junit4:junit4]   2> 11145 T411 C26 P60485 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 11145 T411 C26 P60485 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 11146 T411 C26 P60485 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:34554/solr/collection1/. core=collection1
[junit4:junit4]   2> 11146 T411 C26 P60485 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 11150 T371 C27 P34554 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11153 T371 C27 P34554 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@16db53a; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11153 T371 C27 P34554 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 11166 T371 C27 P34554 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@16db53a; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@16db53a; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 11166 T322 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11167 T371 C27 P34554 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11168 T371 C27 P34554 oass.SolrIndexSearcher.<init> Opening Searcher@14d765e realtime
[junit4:junit4]   2> 11168 T371 C27 P34554 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 11168 T371 C27 P34554 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11168 T371 C27 P34554 /update {commit=true&openSearcher=false&version=2&softCommit=false&waitSearcher=true&commit_end_point=true&wt=javabin} {commit=} 0 18
[junit4:junit4]   2> 11170 T411 C26 P60485 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 11170 T411 C26 P60485 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 11172 T372 C27 P34554 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 11173 T372 C27 P34554 REQ /replication {command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11173 T411 C26 P60485 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 11174 T411 C26 P60485 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 11174 T411 C26 P60485 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 11176 T369 C27 P34554 REQ /replication {command=filelist&qt=/replication&generation=2&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11176 T411 C26 P60485 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 11177 T411 C26 P60485 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904 fullCopy=true
[junit4:junit4]   2> 11195 T371 C27 P34554 REQ /replication {command=filecontent&file=segments_2&qt=/replication&checksum=true&generation=2&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11198 T411 C26 P60485 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 11206 T411 C26 P60485 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20121212105516904
[junit4:junit4]   2> 11207 T411 C26 P60485 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index/ new=./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904
[junit4:junit4]   2> 11208 T411 C26 P60485 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904 forceNew:false
[junit4:junit4]   2> 11209 T411 C26 P60485 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904 lockFactory=org.apache.lucene.store.NativeFSLockFactory@dbe4f3; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 11210 T411 C26 P60485 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11211 T411 C26 P60485 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 11211 T411 C26 P60485 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 11212 T411 C26 P60485 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 11212 T411 C26 P60485 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904
[junit4:junit4]   2> 11213 T411 C26 P60485 oash.SnapPuller$2.onClose removing old index directory ./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index
[junit4:junit4]   2> 11214 T411 C26 P60485 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904 forceNew:true
[junit4:junit4]   2> 11216 T411 C26 P60485 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b3f63d; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 11216 T411 C26 P60485 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11216 T411 C26 P60485 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 11217 T411 C26 P60485 oass.SolrIndexSearcher.<init> Opening Searcher@283a70 main
[junit4:junit4]   2> 11217 T411 C26 P60485 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.RateLimitedDirectoryWrapper
[junit4:junit4]   2> 11220 T410 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@283a70 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 11220 T411 C26 P60485 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11248 T411 C26 P60485 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b3f63d; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1355327705731/jetty4/index.20121212105516904 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b3f63d; maxCacheMB=48.0 maxMergeSizeMB=4.0)),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 11248 T411 C26 P60485 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 11249 T411 C26 P60485 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11249 T411 C26 P60485 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 11249 T411 C26 P60485 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 11249 T411 C26 P60485 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 11249 T411 C26 P60485 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11251 T411 C26 P60485 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 11424 T345 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11425 T345 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "base_url":"http:

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

ts.slow=true -Dtests.locale=en_US -Dtests.timezone=Europe/San_Marino -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] FAILURE  585s J0 | SyncSliceTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 540 seconds
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([A08AAC0EF28723FC:216C221685D843C0]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:165)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:115)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:110)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:563)
[junit4:junit4]    > 	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:224)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:697)
[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$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:46)
[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:49)
[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:46)
[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> 584821 T2281 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Asserting, sim=DefaultSimilarity, locale=en_US, timezone=Europe/San_Marino
[junit4:junit4]   2> NOTE: Linux 3.2.0-34-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=2,free=142625192,total=348127232
[junit4:junit4]   2> NOTE: All tests run in this JVM: [CacheHeaderTest, TestAnalyzedSuggestions, TestRTGBase, TestCollationField, FieldAnalysisRequestHandlerTest, CoreContainerCoreInitFailuresTest, TestCoreContainer, TermVectorComponentDistributedTest, TestLFUCache, TestSearchPerf, NotRequiredUniqueKeyTest, TestXIncludeConfig, TestCharFilters, CoreAdminHandlerTest, TestPseudoReturnFields, DistributedSpellCheckComponentTest, TestQueryTypes, BasicDistributedZkTest, TestPHPSerializedResponseWriter, DistributedTermsComponentTest, TestBadConfig, TestSuggestSpellingConverter, TestStressVersions, TestIndexingPerformance, SuggesterWFSTTest, DefaultValueUpdateProcessorTest, AutoCommitTest, SpellCheckComponentTest, TestSolr4Spatial, NumericFieldsTest, SolrCmdDistributorTest, SchemaVersionSpecificBehaviorTest, DateFieldTest, ResourceLoaderTest, DistanceFunctionTest, FileBasedSpellCheckerTest, TestQuerySenderNoQuery, TestLMDirichletSimilarityFactory, TestBinaryResponseWriter, TestConfig, TestFastLRUCache, SolrPluginUtilsTest, MoreLikeThisHandlerTest, DirectUpdateHandlerOptimizeTest, RequestHandlersTest, AlternateDirectoryTest, BinaryUpdateRequestHandlerTest, TestSolrIndexConfig, SampleTest, FastVectorHighlighterTest, HighlighterConfigTest, SolrCoreCheckLockOnStartupTest, SystemInfoHandlerTest, TestIndexSearcher, TestDistributedSearch, SpellCheckCollatorTest, TestExtendedDismaxParser, SoftAutoCommitTest, BasicZkTest, ZkSolrClientTest, TestFaceting, WordBreakSolrSpellCheckerTest, TestJoin, ConvertedLegacyTest, PeerSyncTest, QueryElevationComponentTest, SortByFunctionTest, TestJmxIntegration, HighlighterTest, TestTrie, SolrRequestParserTest, DirectSolrConnectionTest, TestRemoteStreaming, TestWordDelimiterFilterFactory, PolyFieldTest, SpatialFilterTest, DocumentBuilderTest, MBeansHandlerTest, CurrencyFieldTest, MinimalSchemaTest, StandardRequestHandlerTest, PingRequestHandlerTest, JsonLoaderTest, TestSolrQueryParser, JSONWriterTest, SuggesterFSTTest, TestArbitraryIndexDir, UniqFieldsUpdateProcessorFactoryTest, CSVRequestHandlerTest, BadIndexSchemaTest, NoCacheHeaderTest, BadComponentTest, TestBinaryField, TestSolrDeletionPolicy2, TestMergePolicyConfig, TestNumberUtils, SpellPossibilityIteratorTest, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TimeZoneUtilsTest, URLClassifyProcessorTest, FileUtilsTest, SpellingQueryConverterTest, TestLRUCache, UUIDFieldTest, TestUtils, AnalysisAfterCoreReloadTest, ClusterStateTest, ClusterStateUpdateTest, SyncSliceTest]
[junit4:junit4] Completed on J0 in 584.86s, 1 test, 1 failure <<< FAILURES!

[...truncated 36 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:352: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:438: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1175: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:839: There were test failures: 239 suites, 994 tests, 1 error, 2 failures, 7 ignored (2 assumptions)

Total time: 37 minutes 1 second
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b65 -client -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message