lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk7) - Build # 1503 - Failure!
Date Tue, 02 Oct 2012 14:18:26 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/1503/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

6 tests failed.
REGRESSION:  org.apache.solr.TestDistributedGrouping.testDistribSearch

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:52460/solr returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([B523CDF6FB23A9AA:34C543EE8C7CC996]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
	at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:371)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:394)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:376)
	at org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:146)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:701)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:613)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:777)


REGRESSION:  org.apache.solr.TestDistributedSearch.testDistribSearch

Error Message:
.response[0][range_facet_si]==null

Stack Trace:
junit.framework.AssertionFailedError: .response[0][range_facet_si]==null
	at __randomizedtesting.SeedInfo.seed([B523CDF6FB23A9AA:34C543EE8C7CC996]:0)
	at junit.framework.Assert.fail(Assert.java:50)
	at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:686)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:396)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:376)
	at org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:130)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:701)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:613)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:777)


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

Error Message:
.doc[a_si][0]:org.apache.lucene.document.StoredField:stored,indexed,tokenized<a_si:€�d>!=org.apache.lucene.document.LazyDocument$LazyField:org.apache.lucene.document.LazyDocument$LazyField@77264643

Stack Trace:
junit.framework.AssertionFailedError: .doc[a_si][0]:org.apache.lucene.document.StoredField:stored,indexed,tokenized<a_si:€�d>!=org.apache.lucene.document.LazyDocument$LazyField:org.apache.lucene.document.LazyDocument$LazyField@77264643
	at __randomizedtesting.SeedInfo.seed([B523CDF6FB23A9AA:34C543EE8C7CC996]:0)
	at junit.framework.Assert.fail(Assert.java:50)
	at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:686)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:396)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:376)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkQueries(AbstractFullDistribZkTestBase.java:575)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:77)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:613)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:777)


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

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:60990/solr/collection1 returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([B523CDF6FB23A9AA:34C543EE8C7CC996]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:388)
	at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:270)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:613)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:777)


REGRESSION:  org.apache.solr.handler.component.DistributedQueryElevationComponentTest.testDistribSearch

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:41265/solr returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([B523CDF6FB23A9AA:34C543EE8C7CC996]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
	at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:371)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:394)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:376)
	at org.apache.solr.handler.component.DistributedQueryElevationComponentTest.doTest(DistributedQueryElevationComponentTest.java:83)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:613)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:777)


REGRESSION:  org.apache.solr.handler.component.StatsComponentTest.testStats

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([B523CDF6FB23A9AA:2FA2944993FF6789]:0)
	at org.apache.solr.util.AbstractSolrTestCase.assertQ(AbstractSolrTestCase.java:283)
	at org.apache.solr.handler.component.StatsComponentTest.doTestFacetStatisticsResult(StatsComponentTest.java:239)
	at org.apache.solr.handler.component.StatsComponentTest.testStats(StatsComponentTest.java:68)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:613)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:777)
Caused by: java.lang.NullPointerException
	at org.apache.solr.util.NumberUtils.SortableStr2int(NumberUtils.java:162)
	at org.apache.solr.schema.SortableIntField.toObject(SortableIntField.java:98)
	at org.apache.solr.schema.SortableIntField.toObject(SortableIntField.java:57)
	at org.apache.solr.schema.FieldType.toObject(FieldType.java:344)
	at org.apache.solr.handler.component.AbstractStatsValues.accumulate(StatsValuesFactory.java:129)
	at org.apache.solr.handler.component.SimpleStats.getFieldCacheStats(StatsComponent.java:281)
	at org.apache.solr.handler.component.SimpleStats.getStatsFields(StatsComponent.java:227)
	at org.apache.solr.handler.component.SimpleStats.getStatsCounts(StatsComponent.java:201)
	at org.apache.solr.handler.component.StatsComponent.process(StatsComponent.java:70)
	at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:206)
	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:129)
	at org.apache.solr.core.SolrCore.execute(SolrCore.java:1750)
	at org.apache.solr.util.TestHarness.query(TestHarness.java:364)
	at org.apache.solr.util.TestHarness.query(TestHarness.java:346)
	at org.apache.solr.util.AbstractSolrTestCase.assertQ(AbstractSolrTestCase.java:273)
	... 42 more




Build Log:
[...truncated 8608 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 5 T487 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZkTest-1349187205286
[junit4:junit4]   2> 11 T487 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 29 T488 oaz.Environment.logEnv Server environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 30 T488 oaz.Environment.logEnv Server environment:host.name=serv1
[junit4:junit4]   2> 30 T488 oaz.Environment.logEnv Server environment:java.version=1.7.0
[junit4:junit4]   2> 31 T488 oaz.Environment.logEnv Server environment:java.vendor=IBM Corporation
[junit4:junit4]   2> 31 T488 oaz.Environment.logEnv Server environment:java.home=/opt/ibm/java-x86_64-70/jre
[junit4:junit4]   2> 31 T488 oaz.Environment.logEnv Server environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/easymock-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/guava-r05.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/zookeeper-3.3.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-continuation-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-http-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-security-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-server-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-webapp-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.2.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:/opt/ibm/java-x86_64-70/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.2.jar
[junit4:junit4]   2> 32 T488 oaz.Environment.logEnv Server environment:java.library.path=/opt/ibm/java-x86_64-70/jre/lib/amd64/default:/opt/ibm/java-x86_64-70/jre/lib/amd64:/opt/ibm/java-x86_64-70/jre/lib/amd64/j9vm:/opt/ibm/java-x86_64-70/jre/lib/amd64:/opt/ibm/java-x86_64-70/jre/../lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/lib
[junit4:junit4]   2> 32 T488 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 32 T488 oaz.Environment.logEnv Server environment:java.compiler=j9jit26
[junit4:junit4]   2> 33 T488 oaz.Environment.logEnv Server environment:os.name=Linux
[junit4:junit4]   2> 33 T488 oaz.Environment.logEnv Server environment:os.arch=amd64
[junit4:junit4]   2> 33 T488 oaz.Environment.logEnv Server environment:os.version=3.2.0-31-generic
[junit4:junit4]   2> 33 T488 oaz.Environment.logEnv Server environment:user.name=jenkins
[junit4:junit4]   2> 34 T488 oaz.Environment.logEnv Server environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 34 T488 oaz.Environment.logEnv Server environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 39 T488 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 53 T488 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 65 T488 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 113 T487 oasc.ZkTestServer.run start zk server on port:46101
[junit4:junit4]   2> 132 T487 oaz.Environment.logEnv Client environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 133 T487 oaz.Environment.logEnv Client environment:host.name=serv1
[junit4:junit4]   2> 134 T487 oaz.Environment.logEnv Client environment:java.version=1.7.0
[junit4:junit4]   2> 134 T487 oaz.Environment.logEnv Client environment:java.vendor=IBM Corporation
[junit4:junit4]   2> 135 T487 oaz.Environment.logEnv Client environment:java.home=/opt/ibm/java-x86_64-70/jre
[junit4:junit4]   2> 136 T487 oaz.Environment.logEnv Client environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/easymock-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/guava-r05.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/zookeeper-3.3.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-continuation-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-http-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-security-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-server-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-webapp-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.2.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:/opt/ibm/java-x86_64-70/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.2.jar
[junit4:junit4]   2> 136 T487 oaz.Environment.logEnv Client environment:java.library.path=/opt/ibm/java-x86_64-70/jre/lib/amd64/default:/opt/ibm/java-x86_64-70/jre/lib/amd64:/opt/ibm/java-x86_64-70/jre/lib/amd64/j9vm:/opt/ibm/java-x86_64-70/jre/lib/amd64:/opt/ibm/java-x86_64-70/jre/../lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/lib
[junit4:junit4]   2> 137 T487 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 137 T487 oaz.Environment.logEnv Client environment:java.compiler=j9jit26
[junit4:junit4]   2> 138 T487 oaz.Environment.logEnv Client environment:os.name=Linux
[junit4:junit4]   2> 138 T487 oaz.Environment.logEnv Client environment:os.arch=amd64
[junit4:junit4]   2> 139 T487 oaz.Environment.logEnv Client environment:os.version=3.2.0-31-generic
[junit4:junit4]   2> 139 T487 oaz.Environment.logEnv Client environment:user.name=jenkins
[junit4:junit4]   2> 139 T487 oaz.Environment.logEnv Client environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 140 T487 oaz.Environment.logEnv Client environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 141 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@46b3ad1
[junit4:junit4]   2> 150 T495 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 150 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 155 T495 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 155 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51259
[junit4:junit4]   2> 164 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51259
[junit4:junit4]   2> 166 T491 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 265 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70000 with negotiated timeout 10000 for client /127.0.0.1:51259
[junit4:junit4]   2> 265 T495 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70000, negotiated timeout = 10000
[junit4:junit4]   2> 268 T496 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@46b3ad1 name:ZooKeeperConnection Watcher:127.0.0.1:46101 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 268 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 269 T487 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 293 T492 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a21d2f8e70000
[junit4:junit4]   2> 294 T496 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 295 T489 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x13a21d2f8e70000, likely client has closed socket
[junit4:junit4]   2> 294 T487 oaz.ZooKeeper.close Session: 0x13a21d2f8e70000 closed
[junit4:junit4]   2> 295 T489 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51259 which had sessionid 0x13a21d2f8e70000
[junit4:junit4]   2> 295 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3a760f9f
[junit4:junit4]   2> 298 T497 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 298 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 299 T497 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 306 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51260
[junit4:junit4]   2> 307 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51260
[junit4:junit4]   2> 308 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70001 with negotiated timeout 10000 for client /127.0.0.1:51260
[junit4:junit4]   2> 308 T497 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70001, negotiated timeout = 10000
[junit4:junit4]   2> 309 T498 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a760f9f name:ZooKeeperConnection Watcher:127.0.0.1:46101/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 309 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 310 T487 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 317 T487 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 322 T487 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 325 T487 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 328 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 329 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 335 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 336 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 340 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 341 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 346 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 347 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 351 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 352 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 355 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 356 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 359 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 360 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 364 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 365 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 369 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 369 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 373 T487 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 373 T487 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 379 T492 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a21d2f8e70001
[junit4:junit4]   2> 380 T498 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 380 T487 oaz.ZooKeeper.close Session: 0x13a21d2f8e70001 closed
[junit4:junit4]   2> 380 T489 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51260 which had sessionid 0x13a21d2f8e70001
[junit4:junit4]   2> 453 T487 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 456 T487 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:60990
[junit4:junit4]   2> 457 T487 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 458 T487 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661
[junit4:junit4]   2> 458 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/'
[junit4:junit4]   2> 470 T487 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 471 T487 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 472 T487 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661
[junit4:junit4]   2> 472 T487 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/solr.xml
[junit4:junit4]   2> 473 T487 oasc.CoreContainer.<init> New CoreContainer -915567391
[junit4:junit4]   2> 473 T487 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/'
[junit4:junit4]   2> 474 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/'
[junit4:junit4]   2> 488 T487 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 497 T487 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:46101/solr
[junit4:junit4]   2> 503 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@d4ff447b
[junit4:junit4]   2> 505 T508 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 505 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 507 T508 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 507 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51261
[junit4:junit4]   2> 507 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51261
[junit4:junit4]   2> 509 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70002 with negotiated timeout 5000 for client /127.0.0.1:51261
[junit4:junit4]   2> 509 T508 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70002, negotiated timeout = 5000
[junit4:junit4]   2> 509 T509 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d4ff447b name:ZooKeeperConnection Watcher:127.0.0.1:46101/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 510 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 515 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 518 T487 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 522 T487 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60990_solr
[junit4:junit4]   2> 524 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60990_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60990_solr
[junit4:junit4]   2> 525 T487 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60990_solr
[junit4:junit4]   2> 530 T487 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 530 T487 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 531 T487 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 531 T487 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 532 T487 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 532 T487 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 532 T487 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 533 T487 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 533 T487 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 534 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 548 T487 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 556 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 556 T487 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 559 T487 oasc.Overseer.start Overseer (id=88420332689883138-127.0.0.1:60990_solr-n_0000000000) starting
[junit4:junit4]   2> 560 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 562 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 564 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 566 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 567 T511 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 567 T487 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 571 T487 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 577 T510 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 579 T487 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/collection1
[junit4:junit4]   2> 580 T487 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 582 T487 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 583 T487 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 584 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/collection1/'
[junit4:junit4]   2> 585 T487 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/collection1/lib/README' to classloader
[junit4:junit4]   2> 585 T487 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 613 T487 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 664 T487 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 665 T487 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 670 T487 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1853 T487 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1859 T487 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1862 T487 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1881 T487 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1886 T487 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1891 T487 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1893 T487 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1895 T487 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349187205661/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/control/data/
[junit4:junit4]   2> 1896 T487 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@117bf38c
[junit4:junit4]   2> 1907 T487 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/control/data/index/
[junit4:junit4]   2> 1908 T487 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1909 T487 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/control/data/index forceNew:false
[junit4:junit4]   2> 1915 T487 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@88b83b54; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1916 T487 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1917 T487 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1921 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1922 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1922 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1923 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1924 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1925 T487 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1926 T487 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1928 T487 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1929 T487 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1929 T487 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1930 T487 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1931 T487 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1932 T487 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1932 T487 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1933 T487 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1934 T487 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1935 T487 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1936 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1936 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1937 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1938 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1939 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1940 T487 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1941 T487 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1941 T487 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1943 T487 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1944 T487 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1946 T487 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1948 T487 oass.SolrIndexSearcher.<init> Opening Searcher@e3deaada main
[junit4:junit4]   2> 1977 T487 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1978 T487 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1979 T487 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1986 T487 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2024 T512 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e3deaada main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2043 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 2046 T487 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 2047 T487 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60990/solr shardId:control_shard
[junit4:junit4]   2> 2050 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 2056 T487 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 2065 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:delete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 2065 T487 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2067 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2089 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2090 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 2101 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2569 T487 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2570 T487 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2570 T487 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60990/solr/collection1/
[junit4:junit4]   2> 2571 T487 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2572 T487 oasc.SyncStrategy.syncToMe http://127.0.0.1:60990/solr/collection1/ has no replicas
[junit4:junit4]   2> 2572 T487 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60990/solr/collection1/
[junit4:junit4]   2> 2573 T487 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 2578 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70002 type:create cxid:0x8b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2606 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2611 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2630 T487 oasc.ZkController.register We are http://127.0.0.1:60990/solr/collection1/ and leader is http://127.0.0.1:60990/solr/collection1/
[junit4:junit4]   2> 2631 T487 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60990/solr
[junit4:junit4]   2> 2632 T487 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2635 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2636 T487 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 2636 T487 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2637 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2700 T487 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2702 T487 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:53058
[junit4:junit4]   2> 2703 T487 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2704 T487 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922
[junit4:junit4]   2> 2704 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/'
[junit4:junit4]   2> 2714 T487 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2714 T487 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2715 T487 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922
[junit4:junit4]   2> 2715 T487 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/solr.xml
[junit4:junit4]   2> 2716 T487 oasc.CoreContainer.<init> New CoreContainer 1812312598
[junit4:junit4]   2> 2716 T487 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/'
[junit4:junit4]   2> 2717 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/'
[junit4:junit4]   2> 2730 T487 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2740 T487 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:46101/solr
[junit4:junit4]   2> 2740 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@6640620
[junit4:junit4]   2> 2743 T522 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 2743 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2744 T522 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 2744 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51262
[junit4:junit4]   2> 2744 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51262
[junit4:junit4]   2> 2745 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70003 with negotiated timeout 5000 for client /127.0.0.1:51262
[junit4:junit4]   2> 2746 T522 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70003, negotiated timeout = 5000
[junit4:junit4]   2> 2746 T523 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6640620 name:ZooKeeperConnection Watcher:127.0.0.1:46101/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2746 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2747 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2748 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2749 T487 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53058_solr
[junit4:junit4]   2> 2750 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:53058_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:53058_solr
[junit4:junit4]   2> 2751 T487 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53058_solr
[junit4:junit4]   2> 2753 T509 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2756 T487 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2757 T487 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2758 T487 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2759 T487 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2759 T487 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2760 T487 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2761 T487 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2762 T487 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2762 T487 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2763 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2782 T487 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2790 T487 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/collection1
[junit4:junit4]   2> 2791 T487 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2794 T487 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2795 T487 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2797 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/collection1/'
[junit4:junit4]   2> 2798 T487 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/collection1/lib/README' to classloader
[junit4:junit4]   2> 2799 T487 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2827 T487 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2868 T487 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2870 T487 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2874 T487 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3117 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3125 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3125 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3247 T487 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3254 T487 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3257 T487 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3269 T487 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3275 T487 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3283 T487 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3285 T487 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3287 T487 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349187207922/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty1/
[junit4:junit4]   2> 3288 T487 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@117bf38c
[junit4:junit4]   2> 3290 T487 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty1/index/
[junit4:junit4]   2> 3291 T487 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3292 T487 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty1/index forceNew:false
[junit4:junit4]   2> 3297 T487 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@87dcf61e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3298 T487 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3299 T487 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3304 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3305 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3306 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3307 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3307 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3308 T487 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3309 T487 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3310 T487 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3312 T487 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3313 T487 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3314 T487 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3315 T487 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3317 T487 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3317 T487 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3318 T487 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3319 T487 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3321 T487 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3322 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3323 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3324 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3326 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3327 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3328 T487 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3329 T487 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3331 T487 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3333 T487 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3335 T487 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3337 T487 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3340 T487 oass.SolrIndexSearcher.<init> Opening Searcher@5b15d5a7 main
[junit4:junit4]   2> 3341 T487 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3341 T487 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3343 T487 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3347 T487 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3380 T524 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5b15d5a7 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3629 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3635 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3635 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3900 T487 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3901 T487 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:53058/solr shardId:shard1
[junit4:junit4]   2> 3902 T487 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3909 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70003 type:delete cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3910 T487 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3911 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3913 T487 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3913 T487 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3914 T487 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53058/solr/collection1/
[junit4:junit4]   2> 3914 T487 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3915 T487 oasc.SyncStrategy.syncToMe http://127.0.0.1:53058/solr/collection1/ has no replicas
[junit4:junit4]   2> 3915 T487 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53058/solr/collection1/
[junit4:junit4]   2> 3916 T487 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3921 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4139 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4147 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4147 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4173 T487 oasc.ZkController.register We are http://127.0.0.1:53058/solr/collection1/ and leader is http://127.0.0.1:53058/solr/collection1/
[junit4:junit4]   2> 4174 T487 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53058/solr
[junit4:junit4]   2> 4175 T487 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4177 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4179 T487 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 4179 T487 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4180 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4243 T487 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4245 T487 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:49442
[junit4:junit4]   2> 4246 T487 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4247 T487 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465
[junit4:junit4]   2> 4247 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/'
[junit4:junit4]   2> 4262 T487 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4262 T487 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4263 T487 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465
[junit4:junit4]   2> 4264 T487 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/solr.xml
[junit4:junit4]   2> 4265 T487 oasc.CoreContainer.<init> New CoreContainer -599586246
[junit4:junit4]   2> 4265 T487 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/'
[junit4:junit4]   2> 4266 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/'
[junit4:junit4]   2> 4284 T487 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4299 T487 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:46101/solr
[junit4:junit4]   2> 4300 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b1fec6c
[junit4:junit4]   2> 4303 T534 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 4303 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4311 T534 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 4311 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51264
[junit4:junit4]   2> 4311 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51264
[junit4:junit4]   2> 4313 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70004 with negotiated timeout 5000 for client /127.0.0.1:51264
[junit4:junit4]   2> 4314 T534 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70004, negotiated timeout = 5000
[junit4:junit4]   2> 4314 T535 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b1fec6c name:ZooKeeperConnection Watcher:127.0.0.1:46101/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4315 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4317 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4318 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4320 T487 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49442_solr
[junit4:junit4]   2> 4320 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49442_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49442_solr
[junit4:junit4]   2> 4321 T487 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49442_solr
[junit4:junit4]   2> 4323 T523 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4324 T509 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4325 T487 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4325 T487 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4326 T487 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4326 T487 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4327 T487 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4327 T487 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4327 T487 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4328 T487 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4328 T487 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4329 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4335 T487 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4342 T487 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/collection1
[junit4:junit4]   2> 4343 T487 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4344 T487 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4344 T487 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4347 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/collection1/'
[junit4:junit4]   2> 4348 T487 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/collection1/lib/README' to classloader
[junit4:junit4]   2> 4349 T487 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4391 T487 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4458 T487 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4459 T487 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4466 T487 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4660 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4666 T535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4666 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4667 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4849 T487 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4855 T487 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4858 T487 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4871 T487 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4876 T487 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4882 T487 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4883 T487 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4885 T487 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349187209465/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty2/
[junit4:junit4]   2> 4885 T487 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@117bf38c
[junit4:junit4]   2> 4886 T487 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty2/index/
[junit4:junit4]   2> 4887 T487 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4887 T487 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty2/index forceNew:false
[junit4:junit4]   2> 4891 T487 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@99ed98fd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4891 T487 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4892 T487 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4895 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4896 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4897 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4897 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4898 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4899 T487 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4899 T487 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4901 T487 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4902 T487 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4904 T487 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4905 T487 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4907 T487 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4908 T487 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4909 T487 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4910 T487 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4912 T487 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4913 T487 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4915 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4916 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4917 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4919 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4920 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4922 T487 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4923 T487 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4925 T487 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4927 T487 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4930 T487 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4932 T487 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 4936 T487 oass.SolrIndexSearcher.<init> Opening Searcher@52547bdc main
[junit4:junit4]   2> 4937 T487 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4938 T487 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4940 T487 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4967 T487 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5015 T536 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52547bdc main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5176 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5182 T535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5182 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5182 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5524 T487 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5525 T487 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:49442/solr shardId:shard2
[junit4:junit4]   2> 5526 T487 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 5534 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70004 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 5534 T487 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 5536 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70004 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5539 T487 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 5540 T487 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 5541 T487 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49442/solr/collection1/
[junit4:junit4]   2> 5541 T487 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 5542 T487 oasc.SyncStrategy.syncToMe http://127.0.0.1:49442/solr/collection1/ has no replicas
[junit4:junit4]   2> 5543 T487 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49442/solr/collection1/
[junit4:junit4]   2> 5544 T487 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 5548 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70004 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5688 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5698 T535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5698 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5698 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5701 T487 oasc.ZkController.register We are http://127.0.0.1:49442/solr/collection1/ and leader is http://127.0.0.1:49442/solr/collection1/
[junit4:junit4]   2> 5701 T487 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49442/solr
[junit4:junit4]   2> 5702 T487 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5706 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5708 T487 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5709 T487 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5710 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5792 T487 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5794 T487 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:56072
[junit4:junit4]   2> 5795 T487 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5796 T487 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995
[junit4:junit4]   2> 5797 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/'
[junit4:junit4]   2> 5812 T487 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5812 T487 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5813 T487 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995
[junit4:junit4]   2> 5814 T487 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/solr.xml
[junit4:junit4]   2> 5814 T487 oasc.CoreContainer.<init> New CoreContainer 1784415017
[junit4:junit4]   2> 5815 T487 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/'
[junit4:junit4]   2> 5816 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/'
[junit4:junit4]   2> 5836 T487 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5850 T487 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:46101/solr
[junit4:junit4]   2> 5851 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@15f20fec
[junit4:junit4]   2> 5854 T546 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 5854 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5855 T546 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 5855 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51265
[junit4:junit4]   2> 5855 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51265
[junit4:junit4]   2> 5857 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70005 with negotiated timeout 5000 for client /127.0.0.1:51265
[junit4:junit4]   2> 5857 T546 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70005, negotiated timeout = 5000
[junit4:junit4]   2> 5857 T547 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15f20fec name:ZooKeeperConnection Watcher:127.0.0.1:46101/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5857 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5859 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5860 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5861 T487 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56072_solr
[junit4:junit4]   2> 5862 T492 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a21d2f8e70005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56072_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56072_solr
[junit4:junit4]   2> 5862 T487 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56072_solr
[junit4:junit4]   2> 5871 T535 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5871 T523 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5871 T509 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5873 T487 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5874 T487 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5875 T487 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5875 T487 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5876 T487 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5877 T487 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5877 T487 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5878 T487 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5879 T487 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5879 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5886 T487 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5890 T487 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/collection1
[junit4:junit4]   2> 5891 T487 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5892 T487 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5892 T487 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5893 T487 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/collection1/'
[junit4:junit4]   2> 5894 T487 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/collection1/lib/README' to classloader
[junit4:junit4]   2> 5895 T487 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5923 T487 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5965 T487 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5966 T487 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5971 T487 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6220 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6225 T535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6225 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6226 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6226 T547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6363 T487 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6368 T487 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6371 T487 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6382 T487 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6387 T487 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6392 T487 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6393 T487 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6395 T487 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349187210995/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/
[junit4:junit4]   2> 6395 T487 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@117bf38c
[junit4:junit4]   2> 6396 T487 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index/
[junit4:junit4]   2> 6397 T487 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6397 T487 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index forceNew:false
[junit4:junit4]   2> 6401 T487 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6baf852; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6401 T487 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6402 T487 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6405 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6405 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6406 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6406 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6407 T487 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6408 T487 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6408 T487 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6409 T487 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6411 T487 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6412 T487 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6413 T487 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6413 T487 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6414 T487 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6415 T487 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6416 T487 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6417 T487 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6418 T487 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6419 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6420 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6421 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6422 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6423 T487 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6424 T487 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6425 T487 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6426 T487 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6428 T487 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6429 T487 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6431 T487 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 6434 T487 oass.SolrIndexSearcher.<init> Opening Searcher@3a86a9f main
[junit4:junit4]   2> 6435 T487 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6435 T487 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6437 T487 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6441 T487 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6472 T548 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3a86a9f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6731 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6735 T535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6735 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6736 T547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6736 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6981 T487 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6982 T487 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56072/solr shardId:shard1
[junit4:junit4]   2> 6985 T487 oasc.ZkController.register We are http://127.0.0.1:56072/solr/collection1/ and leader is http://127.0.0.1:53058/solr/collection1/
[junit4:junit4]   2> 6986 T487 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56072/solr
[junit4:junit4]   2> 6987 T487 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 6988 T487 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C26 name=collection1 org.apache.solr.core.SolrCore@e63ae965 url=http://127.0.0.1:56072/solr/collection1 node=127.0.0.1:56072_solr
[junit4:junit4]   2> 6990 T549 C26 P56072 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 6991 T549 C26 P56072 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 6991 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6992 T549 C26 P56072 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:53058/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 6992 T487 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 6993 T487 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6994 T549 C26 P56072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 6994 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6998 T549 C26 P56072 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:56072/solr START replicas=[http://127.0.0.1:53058/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 6999 T549 C26 P56072 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 6999 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@e8df51ee
[junit4:junit4]   2> 6999 T549 C26 P56072 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 7000 T549 C26 P56072 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 7001 T549 C26 P56072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7003 T551 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 7003 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7005 T551 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 7009 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51266
[junit4:junit4]   2> 7011 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51266
[junit4:junit4]   2> 7013 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70006 with negotiated timeout 10000 for client /127.0.0.1:51266
[junit4:junit4]   2> 7013 T551 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70006, negotiated timeout = 10000
[junit4:junit4]   2> 7014 T552 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e8df51ee name:ZooKeeperConnection Watcher:127.0.0.1:46101/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7014 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7016 T487 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 7023 T487 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 7024 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> ASYNC  NEW_CORE C27 name=collection1 org.apache.solr.core.SolrCore@cc884e33 url=http://127.0.0.1:53058/solr/collection1 node=127.0.0.1:53058_solr
[junit4:junit4]   2> 7025 T515 C27 P53058 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 7029 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7031 T487 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):1800
[junit4:junit4]   2> 7032 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7240 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7245 T535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7245 T552 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7245 T547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7245 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7245 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8034 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9036 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C26_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:56072/solr, state=recovering, node_name=127.0.0.1:56072_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 10026 T549 C26 P56072 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 10027 T549 C26 P56072 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10027 T549 C26 P56072 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:53058/solr/collection1/. core=collection1
[junit4:junit4]   2> 10028 T549 C26 P56072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10034 T517 C27 P53058 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 10038 T517 C27 P53058 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10038 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10040 T517 C27 P53058 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@87dcf61e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10041 T517 C27 P53058 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10042 T517 C27 P53058 oass.SolrIndexSearcher.<init> Opening Searcher@c8325dc9 main
[junit4:junit4]   2> 10043 T517 C27 P53058 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10047 T524 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c8325dc9 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10048 T517 C27 P53058 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 16
[junit4:junit4]   2> 10056 T549 C26 P56072 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10057 T549 C26 P56072 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 10063 T549 C26 P56072 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 10065 T518 C27 P53058 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10066 T518 C27 P53058 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10069 T549 C26 P56072 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6baf852; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10069 T549 C26 P56072 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10070 T549 C26 P56072 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10076 T549 C26 P56072 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6baf852; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6baf852; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 10077 T549 C26 P56072 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 10079 T549 C26 P56072 oass.SolrIndexSearcher.<init> Opening Searcher@46ee610 main
[junit4:junit4]   2> 10079 T549 C26 P56072 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10082 T548 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@46ee610 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 10082 T549 C26 P56072 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 10083 T549 C26 P56072 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 10085 T549 C26 P56072 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 10257 T510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10267 T535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10267 T547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10267 T523 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10267 T509 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10267 T552 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11040 T487 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C28 name=collection1 org.apache.solr.core.SolrCore@59f8c15c url=http://127.0.0.1:60990/solr/collection1 node=127.0.0.1:60990_solr
[junit4:junit4]   2> 11051 T501 C28 P60990 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@88b83b54; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11052 T501 C28 P60990 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 11062 T501 C28 P60990 oass.SolrIndexSearcher.<init> Opening Searcher@82c3ed5e realtime
[junit4:junit4]   2> 11063 T501 C28 P60990 /update {version=2&wt=javabin} {deleteByQuery=*:* (-1414725334549397504)} 0 15
[junit4:junit4]   2> 11114 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@93bf170
[junit4:junit4]   2> 11117 T553 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 11117 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 11118 T553 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 11118 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51272
[junit4:junit4]   2> 11120 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51272
[junit4:junit4]   2> 11121 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70007 with negotiated timeout 10000 for client /127.0.0.1:51272
[junit4:junit4]   2> 11121 T553 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70007, negotiated timeout = 10000
[junit4:junit4]   2> 11122 T554 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@93bf170 name:ZooKeeperConnection Watcher:127.0.0.1:46101/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 11122 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 11123 T487 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C29 name=collection1 org.apache.solr.core.SolrCore@82b90155 url=http://127.0.0.1:49442/solr/collection1 node=127.0.0.1:49442_solr
[junit4:junit4]   2> 11135 T527 C29 P49442 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@99ed98fd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11136 T527 C29 P49442 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 11138 T527 C29 P49442 oass.SolrIndexSearcher.<init> Opening Searcher@5c5461c2 realtime
[junit4:junit4]   2> 11146 T519 C27 P53058 oass.SolrIndexSearcher.<init> Opening Searcher@f0742a12 realtime
[junit4:junit4]   2>  C26_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:56072/solr, state=active, node_name=127.0.0.1:56072_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 11153 T539 C26 P56072 oass.SolrIndexSearcher.<init> Opening Searcher@d593688 realtime
[junit4:junit4]   2> 11154 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&_version_=-1414725334650060800&version=2&wt=javabin&update.from=http://127.0.0.1:53058/solr/collection1/} status=0 QTime=2 
[junit4:junit4]   2> 11156 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&version=2&wt=javabin} status=0 QTime=11 
[junit4:junit4]   2> 11156 T527 C29 P49442 /update {version=2&wt=javabin} {deleteByQuery=*:* (-1414725334637477888)} 0 25
[junit4:junit4]   2> 11166 T501 C28 P60990 /update {version=2&wt=javabin} {add=[1 (1414725334669983744)]} 0 3
[junit4:junit4]   2> 11180 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11181 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=8 
[junit4:junit4]   2> 11182 T527 C29 P49442 /update {version=2&wt=javabin} {add=[1]} 0 13
[junit4:junit4]   2> 11186 T501 C28 P60990 /update {version=2&wt=javabin} {add=[2 (1414725334692003840)]} 0 1
[junit4:junit4]   2> 11193 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 11194 T520 C27 P53058 /update {version=2&wt=javabin} {add=[2]} 0 6
[junit4:junit4]   2> 11198 T501 C28 P60990 /update {version=2&wt=javabin} {add=[3 (1414725334704586752)]} 0 1
[junit4:junit4]   2> 11202 T527 C29 P49442 /update {version=2&wt=javabin} {add=[3 (1414725334708781056)]} 0 1
[junit4:junit4]   2> 11208 T501 C28 P60990 /update {version=2&wt=javabin} {add=[4 (1414725334715072512)]} 0 1
[junit4:junit4]   2> 11216 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11217 T520 C27 P53058 /update {version=2&wt=javabin} {add=[4 (1414725334719266816)]} 0 6
[junit4:junit4]   2> 11221 T501 C28 P60990 /update {version=2&wt=javabin} {add=[5 (1414725334728704000)]} 0 1
[junit4:junit4]   2> 11228 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11229 T520 C27 P53058 /update {version=2&wt=javabin} {add=[5]} 0 5
[junit4:junit4]   2> 11244 T501 C28 P60990 /update {version=2&wt=javabin} {add=[6 (1414725334752821248)]} 0 12
[junit4:junit4]   2> 11251 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11252 T520 C27 P53058 /update {version=2&wt=javabin} {add=[6]} 0 5
[junit4:junit4]   2> 11285 T501 C28 P60990 /update {version=2&wt=javabin} {add=[7 (1414725334795812864)]} 0 1
[junit4:junit4]   2> 11294 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11295 T520 C27 P53058 /update {version=2&wt=javabin} {add=[7]} 0 5
[junit4:junit4]   2> 11300 T501 C28 P60990 /update {version=2&wt=javabin} {add=[8 (1414725334811541504)]} 0 2
[junit4:junit4]   2> 11309 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11310 T520 C27 P53058 /update {version=2&wt=javabin} {add=[8 (1414725334816784384)]} 0 7
[junit4:junit4]   2> 11315 T501 C28 P60990 /update {version=2&wt=javabin} {add=[9 (1414725334827270144)]} 0 2
[junit4:junit4]   2> 11322 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11323 T520 C27 P53058 /update {version=2&wt=javabin} {add=[9]} 0 5
[junit4:junit4]   2> 11332 T501 C28 P60990 /update {version=2&wt=javabin} {add=[10 (1414725334840901632)]} 0 6
[junit4:junit4]   2> 11344 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11345 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=7 
[junit4:junit4]   2> 11346 T527 C29 P49442 /update {version=2&wt=javabin} {add=[10]} 0 11
[junit4:junit4]   2> 11351 T501 C28 P60990 /update {version=2&wt=javabin} {add=[11 (1414725334863970304)]} 0 2
[junit4:junit4]   2> 11362 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11363 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=6 
[junit4:junit4]   2> 11363 T527 C29 P49442 /update {version=2&wt=javabin} {add=[11]} 0 9
[junit4:junit4]   2> 11368 T501 C28 P60990 /update {version=2&wt=javabin} {add=[12 (1414725334882844672)]} 0 2
[junit4:junit4]   2> 11375 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11376 T520 C27 P53058 /update {version=2&wt=javabin} {add=[12 (1414725334887038976)]} 0 6
[junit4:junit4]   2> 11380 T501 C28 P60990 /update {version=2&wt=javabin} {add=[13 (1414725334895427584)]} 0 1
[junit4:junit4]   2> 11388 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11389 T520 C27 P53058 /update {version=2&wt=javabin} {add=[13 (1414725334899621888)]} 0 6
[junit4:junit4]   2> 11393 T501 C28 P60990 /update {version=2&wt=javabin} {add=[14 (1414725334909059072)]} 0 1
[junit4:junit4]   2> 11403 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11404 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=6 
[junit4:junit4]   2> 11404 T527 C29 P49442 /update {version=2&wt=javabin} {add=[14]} 0 8
[junit4:junit4]   2> 11408 T501 C28 P60990 /update {version=2&wt=javabin} {add=[15 (1414725334925836288)]} 0 1
[junit4:junit4]   2> 11418 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11419 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=6 
[junit4:junit4]   2> 11420 T527 C29 P49442 /update {version=2&wt=javabin} {add=[15]} 0 9
[junit4:junit4]   2> 11423 T501 C28 P60990 /update {version=2&wt=javabin} {add=[16 (1414725334940516352)]} 0 1
[junit4:junit4]   2> 11437 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 11438 T520 C27 P53058 /update {version=2&wt=javabin} {add=[16 (1414725334944710656)]} 0 12
[junit4:junit4]   2> 11444 T501 C28 P60990 /update {version=2&wt=javabin} {add=[17 (1414725334962536448)]} 0 3
[junit4:junit4]   2> 11454 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 11455 T520 C27 P53058 /update {version=2&wt=javabin} {add=[17]} 0 8
[junit4:junit4]   2> 11458 T501 C28 P60990 /update {version=2&wt=javabin} {add=[100 (1414725334977216512)]} 0 1
[junit4:junit4]   2> 11467 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11468 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11469 T527 C29 P49442 /update {version=2&wt=javabin} {add=[100]} 0 8
[junit4:junit4]   2> 11473 T501 C28 P60990 /update {version=2&wt=javabin} {add=[101 (1414725334992945152)]} 0 2
[junit4:junit4]   2> 11479 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11480 T520 C27 P53058 /update {version=2&wt=javabin} {add=[101 (1414725334997139456)]} 0 5
[junit4:junit4]   2> 11486 T501 C28 P60990 /update {version=2&wt=javabin} {add=[102 (1414725335004479488)]} 0 4
[junit4:junit4]   2> 11492 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11494 T520 C27 P53058 /update {version=2&wt=javabin} {add=[102 (1414725335010770944)]} 0 6
[junit4:junit4]   2> 11498 T501 C28 P60990 /update {version=2&wt=javabin} {add=[103 (1414725335019159552)]} 0 1
[junit4:junit4]   2> 11505 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11507 T520 C27 P53058 /update {version=2&wt=javabin} {add=[103]} 0 6
[junit4:junit4]   2> 11511 T501 C28 P60990 /update {version=2&wt=javabin} {add=[104 (1414725335032791040)]} 0 1
[junit4:junit4]   2> 11518 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11519 T520 C27 P53058 /update {version=2&wt=javabin} {add=[104]} 0 5
[junit4:junit4]   2> 11523 T501 C28 P60990 /update {version=2&wt=javabin} {add=[105 (1414725335045373952)]} 0 1
[junit4:junit4]   2> 11533 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11535 T520 C27 P53058 /update {version=2&wt=javabin} {add=[105]} 0 9
[junit4:junit4]   2> 11539 T501 C28 P60990 /update {version=2&wt=javabin} {add=[106 (1414725335062151168)]} 0 2
[junit4:junit4]   2> 11545 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11546 T520 C27 P53058 /update {version=2&wt=javabin} {add=[106]} 0 5
[junit4:junit4]   2> 11550 T501 C28 P60990 /update {version=2&wt=javabin} {add=[107 (1414725335074734080)]} 0 1
[junit4:junit4]   2> 11560 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11561 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11562 T527 C29 P49442 /update {version=2&wt=javabin} {add=[107]} 0 9
[junit4:junit4]   2> 11566 T501 C28 P60990 /update {version=2&wt=javabin} {add=[108 (1414725335090462720)]} 0 2
[junit4:junit4]   2> 11575 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11576 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11577 T527 C29 P49442 /update {version=2&wt=javabin} {add=[108]} 0 8
[junit4:junit4]   2> 11581 T501 C28 P60990 /update {version=2&wt=javabin} {add=[109 (1414725335106191360)]} 0 1
[junit4:junit4]   2> 11588 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11589 T520 C27 P53058 /update {version=2&wt=javabin} {add=[109 (1414725335110385664)]} 0 5
[junit4:junit4]   2> 11593 T501 C28 P60990 /update {version=2&wt=javabin} {add=[110 (1414725335119822848)]} 0 1
[junit4:junit4]   2> 11599 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11600 T520 C27 P53058 /update {version=2&wt=javabin} {add=[110 (1414725335122968576)]} 0 4
[junit4:junit4]   2> 11604 T501 C28 P60990 /update {version=2&wt=javabin} {add=[111 (1414725335130308608)]} 0 2
[junit4:junit4]   2> 11613 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 11614 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=6 
[junit4:junit4]   2> 11615 T527 C29 P49442 /update {version=2&wt=javabin} {add=[111]} 0 9
[junit4:junit4]   2> 11622 T501 C28 P60990 /update {version=2&wt=javabin} {add=[112 (1414725335146037248)]} 0 5
[junit4:junit4]   2> 11626 T527 C29 P49442 /update {version=2&wt=javabin} {add=[112 (1414725335153377280)]} 0 1
[junit4:junit4]   2> 11630 T501 C28 P60990 /update {version=2&wt=javabin} {add=[113 (1414725335157571584)]} 0 2
[junit4:junit4]   2> 11636 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11638 T520 C27 P53058 /update {version=2&wt=javabin} {add=[113 (1414725335161765888)]} 0 5
[junit4:junit4]   2> 11642 T501 C28 P60990 /update {version=2&wt=javabin} {add=[114 (1414725335170154496)]} 0 2
[junit4:junit4]   2> 11658 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 11659 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=13 
[junit4:junit4]   2> 11660 T527 C29 P49442 /update {version=2&wt=javabin} {add=[114]} 0 16
[junit4:junit4]   2> 11664 T501 C28 P60990 /update {version=2&wt=javabin} {add=[115 (1414725335193223168)]} 0 1
[junit4:junit4]   2> 11671 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11672 T520 C27 P53058 /update {version=2&wt=javabin} {add=[115]} 0 5
[junit4:junit4]   2> 11676 T501 C28 P60990 /update {version=2&wt=javabin} {add=[116 (1414725335205806080)]} 0 1
[junit4:junit4]   2> 11683 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11684 T520 C27 P53058 /update {version=2&wt=javabin} {add=[116]} 0 5
[junit4:junit4]   2> 11688 T501 C28 P60990 /update {version=2&wt=javabin} {add=[117 (1414725335219437568)]} 0 1
[junit4:junit4]   2> 11692 T527 C29 P49442 /update {version=2&wt=javabin} {add=[117 (1414725335223631872)]} 0 1
[junit4:junit4]   2> 11697 T501 C28 P60990 /update {version=2&wt=javabin} {add=[118 (1414725335227826176)]} 0 2
[junit4:junit4]   2> 11706 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11707 T520 C27 P53058 /update {version=2&wt=javabin} {add=[118 (1414725335232020480)]} 0 8
[junit4:junit4]   2> 11711 T501 C28 P60990 /update {version=2&wt=javabin} {add=[119 (1414725335242506240)]} 0 1
[junit4:junit4]   2> 11715 T527 C29 P49442 /update {version=2&wt=javabin} {add=[119 (1414725335246700544)]} 0 1
[junit4:junit4]   2> 11719 T501 C28 P60990 /update {version=2&wt=javabin} {add=[120 (1414725335250894848)]} 0 1
[junit4:junit4]   2> 11726 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11727 T520 C27 P53058 /update {version=2&wt=javabin} {add=[120]} 0 5
[junit4:junit4]   2> 11731 T501 C28 P60990 /update {version=2&wt=javabin} {add=[121 (1414725335263477760)]} 0 1
[junit4:junit4]   2> 11737 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11738 T520 C27 P53058 /update {version=2&wt=javabin} {add=[121]} 0 4
[junit4:junit4]   2> 11746 T501 C28 P60990 /update {version=2&wt=javabin} {add=[122 (1414725335275012096)]} 0 5
[junit4:junit4]   2> 11757 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11758 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11759 T527 C29 P49442 /update {version=2&wt=javabin} {add=[122]} 0 9
[junit4:junit4]   2> 11764 T501 C28 P60990 /update {version=2&wt=javabin} {add=[123 (1414725335297032192)]} 0 2
[junit4:junit4]   2> 11771 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11772 T520 C27 P53058 /update {version=2&wt=javabin} {add=[123 (1414725335302275072)]} 0 5
[junit4:junit4]   2> 11776 T501 C28 P60990 /update {version=2&wt=javabin} {add=[124 (1414725335310663680)]} 0 1
[junit4:junit4]   2> 11784 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11785 T520 C27 P53058 /update {version=2&wt=javabin} {add=[124 (1414725335315906560)]} 0 6
[junit4:junit4]   2> 11789 T501 C28 P60990 /update {version=2&wt=javabin} {add=[125 (1414725335324295168)]} 0 1
[junit4:junit4]   2> 11796 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11797 T520 C27 P53058 /update {version=2&wt=javabin} {add=[125 (1414725335328489472)]} 0 5
[junit4:junit4]   2> 11801 T501 C28 P60990 /update {version=2&wt=javabin} {add=[126 (1414725335336878080)]} 0 1
[junit4:junit4]   2> 11805 T527 C29 P49442 /update {version=2&wt=javabin} {add=[126 (1414725335341072384)]} 0 1
[junit4:junit4]   2> 11809 T501 C28 P60990 /update {version=2&wt=javabin} {add=[127 (1414725335346315264)]} 0 1
[junit4:junit4]   2> 11817 T527 C29 P49442 /update {version=2&wt=javabin} {add=[127 (1414725335350509568)]} 0 5
[junit4:junit4]   2> 11821 T501 C28 P60990 /update {version=2&wt=javabin} {add=[128 (1414725335358898176)]} 0 1
[junit4:junit4]   2> 11828 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11829 T520 C27 P53058 /update {version=2&wt=javabin} {add=[128]} 0 5
[junit4:junit4]   2> 11833 T501 C28 P60990 /update {version=2&wt=javabin} {add=[129 (1414725335370432512)]} 0 1
[junit4:junit4]   2> 11840 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11841 T520 C27 P53058 /update {version=2&wt=javabin} {add=[129 (1414725335374626816)]} 0 5
[junit4:junit4]   2> 11845 T501 C28 P60990 /update {version=2&wt=javabin} {add=[130 (1414725335383015424)]} 0 1
[junit4:junit4]   2> 11851 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11852 T520 C27 P53058 /update {version=2&wt=javabin} {add=[130]} 0 4
[junit4:junit4]   2> 11855 T501 C28 P60990 /update {version=2&wt=javabin} {add=[131 (1414725335394549760)]} 0 0
[junit4:junit4]   2> 11861 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11862 T520 C27 P53058 /update {version=2&wt=javabin} {add=[131]} 0 4
[junit4:junit4]   2> 11868 T501 C28 P60990 /update {version=2&wt=javabin} {add=[132 (1414725335405035520)]} 0 3
[junit4:junit4]   2> 11874 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11876 T520 C27 P53058 /update {version=2&wt=javabin} {add=[132 (1414725335411326976)]} 0 5
[junit4:junit4]   2> 11879 T501 C28 P60990 /update {version=2&wt=javabin} {add=[133 (1414725335418667008)]} 0 1
[junit4:junit4]   2> 11887 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11888 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11889 T527 C29 P49442 /update {version=2&wt=javabin} {add=[133]} 0 7
[junit4:junit4]   2> 11893 T501 C28 P60990 /update {version=2&wt=javabin} {add=[134 (1414725335433347072)]} 0 1
[junit4:junit4]   2> 11896 T527 C29 P49442 /update {version=2&wt=javabin} {add=[134 (1414725335437541376)]} 0 1
[junit4:junit4]   2> 11900 T501 C28 P60990 /update {version=2&wt=javabin} {add=[135 (1414725335440687104)]} 0 1
[junit4:junit4]   2> 11907 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11909 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11909 T527 C29 P49442 /update {version=2&wt=javabin} {add=[135]} 0 7
[junit4:junit4]   2> 11912 T501 C28 P60990 /update {version=2&wt=javabin} {add=[136 (1414725335454318592)]} 0 0
[junit4:junit4]   2> 11916 T527 C29 P49442 /update {version=2&wt=javabin} {add=[136 (1414725335457464320)]} 0 1
[junit4:junit4]   2> 11919 T501 C28 P60990 /update {version=2&wt=javabin} {add=[137 (1414725335460610048)]} 0 1
[junit4:junit4]   2> 11922 T527 C29 P49442 /update {version=2&wt=javabin} {add=[137 (1414725335463755776)]} 0 1
[junit4:junit4]   2> 11925 T501 C28 P60990 /update {version=2&wt=javabin} {add=[138 (1414725335467950080)]} 0 1
[junit4:junit4]   2> 11939 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11940 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=11 
[junit4:junit4]   2> 11941 T527 C29 P49442 /update {version=2&wt=javabin} {add=[138]} 0 13
[junit4:junit4]   2> 11944 T501 C28 P60990 /update {version=2&wt=javabin} {add=[139 (1414725335486824448)]} 0 1
[junit4:junit4]   2> 11947 T527 C29 P49442 /update {version=2&wt=javabin} {add=[139 (1414725335489970176)]} 0 1
[junit4:junit4]   2> 11950 T501 C28 P60990 /update {version=2&wt=javabin} {add=[140 (1414725335493115904)]} 0 1
[junit4:junit4]   2> 11953 T527 C29 P49442 /update {version=2&wt=javabin} {add=[140 (1414725335497310208)]} 0 1
[junit4:junit4]   2> 11956 T501 C28 P60990 /update {version=2&wt=javabin} {add=[141 (1414725335500455936)]} 0 0
[junit4:junit4]   2> 11962 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11963 T520 C27 P53058 /update {version=2&wt=javabin} {add=[141]} 0 4
[junit4:junit4]   2> 11969 T501 C28 P60990 /update {version=2&wt=javabin} {add=[142 (1414725335510941696)]} 0 3
[junit4:junit4]   2> 11979 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 11980 T520 C27 P53058 /update {version=2&wt=javabin} {add=[142]} 0 8
[junit4:junit4]   2> 11984 T501 C28 P60990 /update {version=2&wt=javabin} {add=[143 (1414725335528767488)]} 0 2
[junit4:junit4]   2> 11988 T527 C29 P49442 /update {version=2&wt=javabin} {add=[143 (1414725335531913216)]} 0 2
[junit4:junit4]   2> 11991 T501 C28 P60990 /update {version=2&wt=javabin} {add=[144 (1414725335537156096)]} 0 1
[junit4:junit4]   2> 11997 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11998 T520 C27 P53058 /update {version=2&wt=javabin} {add=[144]} 0 4
[junit4:junit4]   2> 12001 T501 C28 P60990 /update {version=2&wt=javabin} {add=[145 (1414725335546593280)]} 0 1
[junit4:junit4]   2> 12004 T527 C29 P49442 /update {version=2&wt=javabin} {add=[145 (1414725335550787584)]} 0 1
[junit4:junit4]   2> 12007 T501 C28 P60990 /update {version=2&wt=javabin} {add=[146 (1414725335553933312)]} 0 0
[junit4:junit4]   2> 12014 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 12016 T520 C27 P53058 /update {version=2&wt=javabin} {add=[146 (1414725335557079040)]} 0 6
[junit4:junit4]   2> 12019 T501 C28 P60990 /update {version=2&wt=javabin} {add=[147 (1414725335565467648)]} 0 1
[junit4:junit4]   2> 12024 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 12025 T520 C27 P53058 /update {version=2&wt=javabin} {add=[147]} 0 3
[junit4:junit4]   2> 12028 T501 C28 P60990 /update {version=2&wt=javabin} {add=[148 (1414725335575953408)]} 0 0
[junit4:junit4]   2> 12036 T539 C26 P56072 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 12037 T519 C27 P53058 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:49442/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 12038 T527 C29 P49442 /update {version=2&wt=javabin} {add=[148]} 0 7
[junit4:junit4]   2> 12042 T501 C28 P60990 /update {version=2&wt=javabin} {add=[149 (1414725335589584896)]} 0 1
[junit4:junit4]   2> 12048 T528 C29 P49442 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:53058/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 12049 T520 C27 P53058 /update {version=2&wt=javabin} {add=[149]} 0 4
[junit4:junit4]   2> 12051 T501 C28 P60990 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12126 T501 C28 P60990 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@88b83b54; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@88b83b54; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_5.si, _0_nrm.cfe, _1_nrm.cfe, _2_nrm.cfe, _1.si, _3_nrm.cfe, _4_nrm.cfe, _5_nrm.cfe, _6_nrm.cfe, _0_nrm.cfs, _1_nrm.cfs, _2_nrm.cfs, _3_nrm.cfs, _4_nrm.cfs, _5_nrm.cfs, _6_nrm.cfs, _4.si, _0.si, _0.fdt, _1.fdt, _2.fdt, _3.fdt, _4.fdt, _0.fdx, _5.fdt, _1.fdx, _6.fdt, _2.fdx, _3.fdx, _4.fdx, _5.fdx, _6.fdx, _3.si, segments_2, _0.tim, _1.tim, _2.tim, _0.tip, _3.tim, _4.tim, _1.tip, _5.tim, _2.tip, _6.tim, _3.tip, _4.tip, _5.tip, _6.tip, _0.fnm, _0.frq, _1.fnm, _1.frq, _2.frq, _2.fnm, _3.fnm, _3.frq, _4.fnm, _4.frq, _6.si, _5.frq, _5.fnm, _6.frq, _6.fnm, _2.si, _0.prx, _1.prx, _2.prx, _3.prx, _4.prx, _5.prx, _6.prx]
[junit4:junit4]   2> 12127 T501 C28 P60990 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 12130 T501 C28 P60990 oass.SolrIndexSearcher.<init> Opening Searcher@716a7daa main
[junit4:junit4]   2> 12130 T501 C28 P60990 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12133 T512 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@716a7daa main{StandardDirectoryReader(segments_2:15:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10 _3(5.0):C10 _4(5.0):C10 _5(5.0):C10 _6(5.0):C7)}
[junit4:junit4]   2> 12134 T501 C28 P60990 /update {version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 83
[junit4:junit4]   2> 12137 T527 C29 P49442 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12186 T527 C29 P49442 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@99ed98fd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@99ed98fd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_3.fdx, _0_nrm.cfe, _3.si, _1_nrm.cfe, _2_nrm.cfe, _3_nrm.cfe, _1.si, segments_2, _0.tim, _1.tim, _2.tim, _0_nrm.cfs, _3.tim, _0.tip, _1_nrm.cfs, _2_nrm.cfs, _1.tip, _3_nrm.cfs, _2.tip, _3.tip, _0.frq, _0.fnm, _1.frq, _1.fnm, _2.fnm, _2.frq, _3.frq, _3.fnm, _2.si, _0.si, _0.fdt, _0.prx, _1.prx, _1.fdt, _2.fdt, _2.prx, _3.fdt, _3.prx, _0.fdx, _1.fdx, _2.fdx]
[junit4:junit4]   2> 12187 T527 C29 P49442 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 12189 T527 C29 P49442 oass.SolrIndexSearcher.<init> Opening Searcher@c1f91056 main
[junit4:junit4]   2> 12190 T527 C29 P49442 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12193 T536 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c1f91056 main{StandardDirectoryReader(segments_2:9:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10 _3(5.0):C5)}
[junit4:junit4]   2> 12193 T527 C29 P49442 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:53058/solr/collection1/, StdNode: http://127.0.0.1:56072/solr/collection1/]
[junit4:junit4]   2> 12196 T539 C26 P56072 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12196 T519 C27 P53058 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12256 T539 C26 P56072 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6baf852; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6baf852; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_3.fdx, _0_nrm.cfe, _3.si, _1_nrm.cfe, _2_nrm.cfe, _3_nrm.cfe, _1.si, segments_3, _0.tim, _1.tim, _2.tim, _0_nrm.cfs, _3.tim, _0.tip, _1_nrm.cfs, _2_nrm.cfs, _1.tip, _3_nrm.cfs, _2.tip, _3.tip, _0.frq, _0.fnm, _1.frq, _1.fnm, _2.fnm, _2.frq, _3.frq, _3.fnm, _2.si, _0.si, _0.fdt, _0.prx, _1.prx, _1.fdt, _2.fdt, _2.prx, _3.fdt, _3.prx, _0.fdx, _1.fdx, _2.fdx]
[junit4:junit4]   2> 12257 T539 C26 P56072 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 12258 T519 C27 P53058 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@87dcf61e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349187205286/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@87dcf61e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_3.fdx, _0_nrm.cfe, _3.si, _1_nrm.cfe, _2_nrm.cfe, _3_nrm.cfe, _1.si, segments_2, _0.tim, _1.tim, _2.tim, _0_nrm.cfs, _3.tim, _0.tip, _1_nrm.cfs, _2_nrm.cfs, _1.tip, _3_nrm.cfs, _2.tip, _3.tip, _0.frq, _0.fnm, _1.frq, _1.fnm, _2.fnm, _2.frq, _3.frq, _3.fnm, _2.si, _0.si, _0.fdt, _0.prx, _1.prx, _1.fdt, _2.fdt, _2.prx, _3.fdt, _3.prx, _0.fdx, _1.fdx, _2.fdx]
[junit4:junit4]   2> 12259 T519 C27 P53058 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 12260 T539 C26 P56072 oass.SolrIndexSearcher.<init> Opening Searcher@951a0983 main
[junit4:junit4]   2> 12260 T539 C26 P56072 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12261 T519 C27 P53058 oass.SolrIndexSearcher.<init> Opening Searcher@7179c519 main
[junit4:junit4]   2> 12262 T519 C27 P53058 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12263 T548 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@951a0983 main{StandardDirectoryReader(segments_3:10:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10 _3(5.0):C2)}
[junit4:junit4]   2> 12264 T539 C26 P56072 /update {softCommit=false&commit_end_point=true&expungeDeletes=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 68
[junit4:junit4]   2> 12264 T524 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7179c519 main{StandardDirectoryReader(segments_2:9:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10 _3(5.0):C2)}
[junit4:junit4]   2> 12265 T519 C27 P53058 /update {softCommit=false&commit_end_point=true&expungeDeletes=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 69
[junit4:junit4]   2> 12266 T527 C29 P49442 /update {version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 129
[junit4:junit4]   2> 12269 T501 C28 P60990 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_ti1+desc} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12271 T487 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 12274 T487 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46101/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5bee3e87
[junit4:junit4]   2> 12276 T558 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:46101
[junit4:junit4]   2> 12276 T487 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12277 T558 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46101, initiating session
[junit4:junit4]   2> 12277 T489 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51278
[junit4:junit4]   2> 12278 T489 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51278
[junit4:junit4]   2> 12279 T491 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a21d2f8e70008 with negotiated timeout 10000 for client /127.0.0.1:51278
[junit4:junit4]   2> 12279 T558 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:46101, sessionid = 0x13a21d2f8e70008, negotiated timeout = 10000
[junit4:junit4]   2> 12279 T559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5bee3e87 name:ZooKeeperConnection Watcher:127.0.0.1:46101/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12279 T487 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12280 T487 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 12286 T516 C27 P53058 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1349187217564&version=2&rows=10&wt=javabin&collection=collection1&sort=n_ti1+desc&shard.url=127.0.0.1:53058/solr/collection1/|127.0.0.1:56072/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 12286 T529 C29 P49442 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1349187217564&version=2&rows=10&wt=javabin&collection=collection1&sort=n_ti1+desc&shard.url=127.0.0.1:49442/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 12290 T516 C27 P53058 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1349187217564&ids=4,125,138,8&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:53058/solr/collection1/|127.0.0.1:56072/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12290 T529 C29 P49442 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1349187217564&ids=145,103,126,105,17,131&version=2&wt=javabin&collection

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

rg.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:613)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:777)
[junit4:junit4]   2> 16352 T2431 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {multiDefault=PostingsFormat(name=Memory doPackFST= false), other_tl1=PostingsFormat(name=Lucene40WithOrds), n_ti1=PostingsFormat(name=MockSep), rnd_b=PostingsFormat(name=MockSep), SubjectTerms_mfacet=PostingsFormat(name=Lucene40WithOrds), n_dt1=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=Lucene40WithOrds), a_t=PostingsFormat(name=Lucene40WithOrds), timestamp=PostingsFormat(name=Lucene40WithOrds), id=PostingsFormat(name=MockSep), n_tdt1=PostingsFormat(name=Lucene40WithOrds), range_facet_si=PostingsFormat(name=Memory doPackFST= false), range_facet_l=PostingsFormat(name=Lucene40WithOrds), range_facet_sl=MockVariableIntBlock(baseBlockSize=26), _version_=PostingsFormat(name=Lucene40WithOrds), n_tl1=PostingsFormat(name=Memory doPackFST= false), foo_b=MockVariableIntBlock(baseBlockSize=26), foo_d=PostingsFormat(name=MockSep), foo_f=MockVariableIntBlock(baseBlockSize=26), text=MockVariableIntBlock(baseBlockSize=26), n_tf1=PostingsFormat(name=Lucene40WithOrds), n_td1=PostingsFormat(name=Memory doPackFST= false), a_si=PostingsFormat(name=Memory doPackFST= false), n_l1=PostingsFormat(name=Lucene40WithOrds), n_f1=PostingsFormat(name=Memory doPackFST= false), n_d1=PostingsFormat(name=Lucene40WithOrds), oddField_s=MockVariableIntBlock(baseBlockSize=26)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=pt_PT, timezone=America/El_Salvador
[junit4:junit4]   2> NOTE: Linux 3.2.0-31-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=105972504,total=230555648
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestStressLucene, UpdateParamsTest, TestPropInjectDefaults, TestOmitPositions, RAMDirectoryFactoryTest, TestQuerySenderListener, ZkCLITest, MultiTermTest, TestStressRecovery, TestTrie, SuggesterTSTTest, FieldMutatingUpdateProcessorTest, OpenExchangeRatesOrgProviderTest, SyncSliceTest, FileUtilsTest, SolrInfoMBeanTest, TestSort, LukeRequestHandlerTest, ConvertedLegacyTest, DOMUtilTest, DocumentBuilderTest, TestCSVResponseWriter, BadIndexSchemaTest, SpellingQueryConverterTest, DirectUpdateHandlerTest, TestSolr4Spatial, IndexReaderFactoryTest, HighlighterTest, TestSolrDeletionPolicy1, UUIDFieldTest, ZkNodePropsTest, DisMaxRequestHandlerTest, TestWriterPerf, TestCodecSupport, TestBinaryField, TestReplicationHandler, SuggesterTest, QueryParsingTest, RequiredFieldsTest, IndexSchemaTest, TestHashPartitioner, TestPhraseSuggestions, TestArbitraryIndexDir, TestJmxIntegration, TestQueryUtils, FullSolrCloudDistribCmdsTest, PolyFieldTest, TestCSVLoader, StandardRequestHandlerTest, TestUpdate, OverseerTest, AnalysisAfterCoreReloadTest, PingRequestHandlerTest, BasicZkTest, TestReversedWildcardFilterFactory, RecoveryZkTest, SpellCheckComponentTest, LeaderElectionTest, DistributedSpellCheckComponentTest, ZkControllerTest, TestDistributedSearch, AutoCommitTest, TestFaceting, StatsComponentTest, TestRecovery, TestGroupingSearch, FileBasedSpellCheckerTest, PeerSyncTest, BasicFunctionalityTest, SignatureUpdateProcessorFactoryTest, SolrRequestParserTest, QueryEqualityTest, DistributedQueryElevationComponentTest, TestPseudoReturnFields, TermsComponentTest, XsltUpdateRequestHandlerTest, XmlUpdateRequestHandlerTest, TermVectorComponentTest, JsonLoaderTest, TestSurroundQueryParser, DocumentAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, DebugComponentTest, ReturnFieldsTest, CopyFieldTest, SolrCmdDistributorTest, TestLuceneMatchVersion, TestPropInject, PrimitiveFieldTypeTest, TestMergePolicyConfig, TestCollationField, NumericFieldsTest, TestNumberUtils, TestSolrCoreProperties, TestJmxMonitoredMap, TestConfig, EchoParamsTest, TestLMDirichletSimilarityFactory, TestPluginEnable, TestDFRSimilarityFactory, TimeZoneUtilsTest, DateMathParserTest, PluginInfoTest, TestSolrXMLSerializer, TestLRUCache, TestUtils, PathHierarchyTokenizerFactoryTest, TestCharFilters, BasicDistributedZk2Test]
[junit4:junit4] Completed on J1 in 16.38s, 1 test, 1 failure <<< FAILURES!

[...truncated 35 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:38: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:180: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1143: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:816: There were test failures: 230 suites, 954 tests, 4 errors, 2 failures, 7 ignored (2 assumptions)

Total time: 22 minutes 53 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message