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 # 1297 - Failure!
Date Sat, 22 Sep 2012 21:22:51 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/1297/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

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

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:45580/solr returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([2853B4BCF7A1E2D9:A9B53AA480FE82E5]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
	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:
Server at http://127.0.0.1:49498/solr returned non ok status:500, message:Server Error

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:49498/solr returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([2853B4BCF7A1E2D9:A9B53AA480FE82E5]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
	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.TestDistributedSearch.doTest(TestDistributedSearch.java:134)
	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.BasicDistributedZkTest.testDistribSearch

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:36044/solr/collection1 returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([2853B4BCF7A1E2D9:A9B53AA480FE82E5]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
	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.StatsComponentTest.testStats

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([2853B4BCF7A1E2D9:B2D2ED039F7D2CFA]: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:1701)
	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 7880 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 4 T492 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-1348348614438
[junit4:junit4]   2> 5 T492 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 6 T493 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 7 T493 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 8 T493 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 105 T492 oasc.ZkTestServer.run start zk server on port:44271
[junit4:junit4]   2> 106 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@16eb4ef8
[junit4:junit4]   2> 108 T498 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 108 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 108 T498 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 108 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37341
[junit4:junit4]   2> 109 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37341
[junit4:junit4]   2> 109 T496 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 164 T498 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0000, negotiated timeout = 10000
[junit4:junit4]   2> 164 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0000 with negotiated timeout 10000 for client /127.0.0.1:37341
[junit4:junit4]   2> 164 T499 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16eb4ef8 name:ZooKeeperConnection Watcher:127.0.0.1:44271 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 165 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 166 T492 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 167 T497 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139efd7172a0000
[junit4:junit4]   2> 168 T494 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:37341 which had sessionid 0x139efd7172a0000
[junit4:junit4]   2> 168 T499 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 168 T492 oaz.ZooKeeper.close Session: 0x139efd7172a0000 closed
[junit4:junit4]   2> 169 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@d6d82b4b
[junit4:junit4]   2> 171 T500 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 171 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 172 T500 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 172 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37342
[junit4:junit4]   2> 172 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37342
[junit4:junit4]   2> 173 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0001 with negotiated timeout 10000 for client /127.0.0.1:37342
[junit4:junit4]   2> 173 T500 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0001, negotiated timeout = 10000
[junit4:junit4]   2> 173 T501 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d6d82b4b name:ZooKeeperConnection Watcher:127.0.0.1:44271/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 174 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 174 T492 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 177 T492 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 179 T492 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 180 T492 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 182 T492 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> 185 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 188 T492 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> 191 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 194 T492 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> 196 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 199 T492 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> 200 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 201 T492 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> 202 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 204 T492 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> 205 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 207 T492 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> 208 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 210 T492 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> 211 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 213 T492 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> 214 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 216 T492 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> 217 T492 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 219 T497 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139efd7172a0001
[junit4:junit4]   2> 219 T501 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 219 T494 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:37342 which had sessionid 0x139efd7172a0001
[junit4:junit4]   2> 219 T492 oaz.ZooKeeper.close Session: 0x139efd7172a0001 closed
[junit4:junit4]   2> 292 T492 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 296 T492 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:36044
[junit4:junit4]   2> 296 T492 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 297 T492 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-1348348614654
[junit4:junit4]   2> 297 T492 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-1348348614654/'
[junit4:junit4]   2> 306 T492 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 306 T492 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 307 T492 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-1348348614654
[junit4:junit4]   2> 307 T492 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-1348348614654/solr.xml
[junit4:junit4]   2> 308 T492 oasc.CoreContainer.<init> New CoreContainer 1661232587
[junit4:junit4]   2> 308 T492 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-1348348614654/'
[junit4:junit4]   2> 308 T492 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-1348348614654/'
[junit4:junit4]   2> 319 T492 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 326 T492 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44271/solr
[junit4:junit4]   2> 327 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@bbecbd8b
[junit4:junit4]   2> 329 T511 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 329 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 330 T511 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 330 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37343
[junit4:junit4]   2> 330 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37343
[junit4:junit4]   2> 332 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0002 with negotiated timeout 5000 for client /127.0.0.1:37343
[junit4:junit4]   2> 332 T511 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0002, negotiated timeout = 5000
[junit4:junit4]   2> 332 T512 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bbecbd8b name:ZooKeeperConnection Watcher:127.0.0.1:44271/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 333 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 334 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 335 T492 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 336 T492 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36044_solr
[junit4:junit4]   2> 337 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:36044_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:36044_solr
[junit4:junit4]   2> 337 T492 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36044_solr
[junit4:junit4]   2> 339 T492 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 340 T492 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 340 T492 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 340 T492 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 341 T492 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 341 T492 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 342 T492 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 342 T492 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 342 T492 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 343 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 347 T492 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 351 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 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> 352 T492 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 354 T492 oasc.Overseer.start Overseer (id=88365374796070914-127.0.0.1:36044_solr-n_0000000000) starting
[junit4:junit4]   2> 355 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 355 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 357 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 358 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 359 T514 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 359 T492 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 364 T492 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 367 T513 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 369 T492 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-1348348614654/collection1
[junit4:junit4]   2> 370 T492 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 371 T492 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 371 T492 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 372 T492 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-1348348614654/collection1/'
[junit4:junit4]   2> 373 T492 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-1348348614654/collection1/lib/README' to classloader
[junit4:junit4]   2> 373 T492 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-1348348614654/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 398 T492 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 432 T492 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 433 T492 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 437 T492 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 843 T492 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 847 T492 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 849 T492 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 859 T492 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 862 T492 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 865 T492 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 867 T492 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 868 T492 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-1348348614654/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/control/data/
[junit4:junit4]   2> 869 T492 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@f7afd4b7
[junit4:junit4]   2> 872 T492 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/control/data/index/
[junit4:junit4]   2> 872 T492 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 873 T492 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-1348348614438/control/data/index forceNew:false
[junit4:junit4]   2> 876 T492 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 877 T492 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 877 T492 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 879 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 880 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 880 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 880 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 881 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 882 T492 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 882 T492 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 883 T492 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 883 T492 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 884 T492 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 884 T492 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 885 T492 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 885 T492 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 886 T492 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 886 T492 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 887 T492 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 887 T492 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 888 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 888 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 889 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 890 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 890 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 891 T492 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 891 T492 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 892 T492 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 893 T492 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 894 T492 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 895 T492 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> 897 T492 oass.SolrIndexSearcher.<init> Opening Searcher@9c3f2553 main
[junit4:junit4]   2> 901 T492 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 902 T492 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 902 T492 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 906 T492 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 928 T515 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9c3f2553 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 935 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 937 T492 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 937 T492 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:36044/solr shardId:control_shard
[junit4:junit4]   2> 938 T492 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 942 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:delete cxid:0x62 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> 943 T492 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 943 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1370 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1371 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1376 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1445 T492 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1446 T492 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1447 T492 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:36044/solr/collection1/
[junit4:junit4]   2> 1448 T492 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1448 T492 oasc.SyncStrategy.syncToMe http://127.0.0.1:36044/solr/collection1/ has no replicas
[junit4:junit4]   2> 1449 T492 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:36044/solr/collection1/
[junit4:junit4]   2> 1450 T492 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1453 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1882 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1887 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1905 T492 oasc.ZkController.register We are http://127.0.0.1:36044/solr/collection1/ and leader is http://127.0.0.1:36044/solr/collection1/
[junit4:junit4]   2> 1906 T492 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36044/solr
[junit4:junit4]   2> 1907 T492 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1908 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1909 T492 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 1909 T492 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1910 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1965 T492 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1966 T492 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:52960
[junit4:junit4]   2> 1967 T492 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1967 T492 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-1348348616347
[junit4:junit4]   2> 1968 T492 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-1348348616347/'
[junit4:junit4]   2> 1976 T492 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1977 T492 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1977 T492 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-1348348616347
[junit4:junit4]   2> 1978 T492 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-1348348616347/solr.xml
[junit4:junit4]   2> 1978 T492 oasc.CoreContainer.<init> New CoreContainer 308941488
[junit4:junit4]   2> 1979 T492 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-1348348616347/'
[junit4:junit4]   2> 1979 T492 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-1348348616347/'
[junit4:junit4]   2> 1991 T492 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1999 T492 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44271/solr
[junit4:junit4]   2> 1999 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@dd80f4ea
[junit4:junit4]   2> 2002 T525 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 2002 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2002 T525 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 2003 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37345
[junit4:junit4]   2> 2003 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37345
[junit4:junit4]   2> 2004 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0003 with negotiated timeout 5000 for client /127.0.0.1:37345
[junit4:junit4]   2> 2004 T525 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0003, negotiated timeout = 5000
[junit4:junit4]   2> 2004 T526 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@dd80f4ea name:ZooKeeperConnection Watcher:127.0.0.1:44271/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2004 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2005 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2006 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2007 T492 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52960_solr
[junit4:junit4]   2> 2007 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:52960_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:52960_solr
[junit4:junit4]   2> 2008 T492 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52960_solr
[junit4:junit4]   2> 2009 T512 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2010 T492 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2010 T492 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2011 T492 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2011 T492 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2011 T492 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2012 T492 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2012 T492 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2013 T492 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2013 T492 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2013 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2019 T492 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2022 T492 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-1348348616347/collection1
[junit4:junit4]   2> 2023 T492 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2023 T492 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2024 T492 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2025 T492 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-1348348616347/collection1/'
[junit4:junit4]   2> 2025 T492 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-1348348616347/collection1/lib/README' to classloader
[junit4:junit4]   2> 2026 T492 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-1348348616347/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2051 T492 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2087 T492 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2088 T492 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2092 T492 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2390 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2393 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2393 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2461 T492 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2466 T492 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2468 T492 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2477 T492 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2481 T492 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2484 T492 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2485 T492 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2486 T492 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-1348348616347/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty1/
[junit4:junit4]   2> 2487 T492 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@f7afd4b7
[junit4:junit4]   2> 2487 T492 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty1/index/
[junit4:junit4]   2> 2488 T492 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2489 T492 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-1348348614438/jetty1/index forceNew:false
[junit4:junit4]   2> 2492 T492 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2492 T492 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2493 T492 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2495 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2495 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2496 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2496 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2497 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2497 T492 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2498 T492 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2499 T492 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2499 T492 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2500 T492 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2501 T492 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2501 T492 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2502 T492 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2502 T492 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2503 T492 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2503 T492 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2504 T492 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2505 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2505 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2506 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2507 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2507 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2508 T492 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2509 T492 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2509 T492 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2510 T492 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2511 T492 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2513 T492 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> 2515 T492 oass.SolrIndexSearcher.<init> Opening Searcher@a064de9b main
[junit4:junit4]   2> 2516 T492 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2516 T492 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2517 T492 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2521 T492 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2542 T527 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a064de9b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2896 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2901 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2901 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3048 T492 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3049 T492 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:52960/solr shardId:shard1
[junit4:junit4]   2> 3050 T492 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3056 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0003 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> 3056 T492 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3057 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3058 T492 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3059 T492 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3059 T492 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52960/solr/collection1/
[junit4:junit4]   2> 3060 T492 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3060 T492 oasc.SyncStrategy.syncToMe http://127.0.0.1:52960/solr/collection1/ has no replicas
[junit4:junit4]   2> 3061 T492 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52960/solr/collection1/
[junit4:junit4]   2> 3061 T492 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3064 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3404 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3410 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3410 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3417 T492 oasc.ZkController.register We are http://127.0.0.1:52960/solr/collection1/ and leader is http://127.0.0.1:52960/solr/collection1/
[junit4:junit4]   2> 3417 T492 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52960/solr
[junit4:junit4]   2> 3418 T492 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3419 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3420 T492 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3420 T492 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3421 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3470 T492 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3472 T492 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:55260
[junit4:junit4]   2> 3472 T492 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3472 T492 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-1348348617858
[junit4:junit4]   2> 3473 T492 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-1348348617858/'
[junit4:junit4]   2> 3481 T492 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3481 T492 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3482 T492 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-1348348617858
[junit4:junit4]   2> 3482 T492 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-1348348617858/solr.xml
[junit4:junit4]   2> 3482 T492 oasc.CoreContainer.<init> New CoreContainer -1904933287
[junit4:junit4]   2> 3483 T492 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-1348348617858/'
[junit4:junit4]   2> 3483 T492 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-1348348617858/'
[junit4:junit4]   2> 3494 T492 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3501 T492 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44271/solr
[junit4:junit4]   2> 3502 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@89f043f5
[junit4:junit4]   2> 3504 T537 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 3504 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3505 T537 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 3505 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37347
[junit4:junit4]   2> 3505 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37347
[junit4:junit4]   2> 3506 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0004 with negotiated timeout 5000 for client /127.0.0.1:37347
[junit4:junit4]   2> 3506 T537 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0004, negotiated timeout = 5000
[junit4:junit4]   2> 3507 T538 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@89f043f5 name:ZooKeeperConnection Watcher:127.0.0.1:44271/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3507 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3508 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3509 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3510 T492 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55260_solr
[junit4:junit4]   2> 3511 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55260_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55260_solr
[junit4:junit4]   2> 3511 T492 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55260_solr
[junit4:junit4]   2> 3513 T512 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3513 T526 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3514 T492 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3515 T492 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3515 T492 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3516 T492 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3516 T492 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3516 T492 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3517 T492 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3517 T492 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3518 T492 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3519 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3524 T492 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3528 T492 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-1348348617858/collection1
[junit4:junit4]   2> 3528 T492 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3529 T492 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3529 T492 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3530 T492 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-1348348617858/collection1/'
[junit4:junit4]   2> 3531 T492 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-1348348617858/collection1/lib/README' to classloader
[junit4:junit4]   2> 3532 T492 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-1348348617858/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3554 T492 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3587 T492 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3588 T492 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3592 T492 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3914 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3918 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3919 T538 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3922 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3963 T492 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3968 T492 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3970 T492 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3979 T492 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3982 T492 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3986 T492 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3987 T492 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3994 T492 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-1348348617858/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty2/
[junit4:junit4]   2> 3994 T492 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@f7afd4b7
[junit4:junit4]   2> 3995 T492 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty2/index/
[junit4:junit4]   2> 3996 T492 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3996 T492 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-1348348614438/jetty2/index forceNew:false
[junit4:junit4]   2> 3999 T492 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4000 T492 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4000 T492 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4002 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4003 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4003 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4004 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4004 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4005 T492 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4005 T492 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4006 T492 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4007 T492 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4007 T492 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4008 T492 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4008 T492 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4009 T492 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4009 T492 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4010 T492 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4011 T492 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4011 T492 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4012 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4013 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4013 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4014 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4015 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4015 T492 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4016 T492 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4016 T492 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4017 T492 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4019 T492 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4020 T492 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> 4022 T492 oass.SolrIndexSearcher.<init> Opening Searcher@bda1d4a7 main
[junit4:junit4]   2> 4023 T492 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4024 T492 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4024 T492 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4028 T492 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4052 T539 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bda1d4a7 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4422 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4426 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4426 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4426 T538 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4561 T492 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4561 T492 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55260/solr shardId:shard2
[junit4:junit4]   2> 4562 T492 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 4567 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0004 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> 4568 T492 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4568 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0004 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4570 T492 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4570 T492 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4570 T492 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55260/solr/collection1/
[junit4:junit4]   2> 4571 T492 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4571 T492 oasc.SyncStrategy.syncToMe http://127.0.0.1:55260/solr/collection1/ has no replicas
[junit4:junit4]   2> 4572 T492 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55260/solr/collection1/
[junit4:junit4]   2> 4572 T492 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 4576 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0004 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4929 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4935 T538 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4935 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4935 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4978 T492 oasc.ZkController.register We are http://127.0.0.1:55260/solr/collection1/ and leader is http://127.0.0.1:55260/solr/collection1/
[junit4:junit4]   2> 4979 T492 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55260/solr
[junit4:junit4]   2> 4979 T492 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4981 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4983 T492 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 4983 T492 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4984 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5042 T492 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5044 T492 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:56706
[junit4:junit4]   2> 5045 T492 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5045 T492 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-1348348619423
[junit4:junit4]   2> 5045 T492 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-1348348619423/'
[junit4:junit4]   2> 5054 T492 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5054 T492 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5055 T492 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-1348348619423
[junit4:junit4]   2> 5055 T492 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-1348348619423/solr.xml
[junit4:junit4]   2> 5056 T492 oasc.CoreContainer.<init> New CoreContainer 271355196
[junit4:junit4]   2> 5056 T492 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-1348348619423/'
[junit4:junit4]   2> 5057 T492 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-1348348619423/'
[junit4:junit4]   2> 5068 T492 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5075 T492 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44271/solr
[junit4:junit4]   2> 5076 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@f7bd5679
[junit4:junit4]   2> 5078 T549 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 5078 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5079 T549 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 5079 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37349
[junit4:junit4]   2> 5080 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37349
[junit4:junit4]   2> 5081 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0005 with negotiated timeout 5000 for client /127.0.0.1:37349
[junit4:junit4]   2> 5081 T549 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0005, negotiated timeout = 5000
[junit4:junit4]   2> 5081 T550 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f7bd5679 name:ZooKeeperConnection Watcher:127.0.0.1:44271/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5081 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5082 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5083 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5083 T492 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56706_solr
[junit4:junit4]   2> 5084 T497 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139efd7172a0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56706_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56706_solr
[junit4:junit4]   2> 5084 T492 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56706_solr
[junit4:junit4]   2> 5086 T526 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5086 T538 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5086 T512 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5087 T492 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5088 T492 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5088 T492 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5088 T492 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5089 T492 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5089 T492 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5090 T492 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5090 T492 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5090 T492 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5091 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5096 T492 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5099 T492 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-1348348619423/collection1
[junit4:junit4]   2> 5100 T492 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5100 T492 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5101 T492 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5102 T492 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-1348348619423/collection1/'
[junit4:junit4]   2> 5103 T492 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-1348348619423/collection1/lib/README' to classloader
[junit4:junit4]   2> 5103 T492 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-1348348619423/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5128 T492 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5164 T492 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5165 T492 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5169 T492 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5439 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5446 T538 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5446 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5446 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5447 T550 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5551 T492 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5555 T492 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5558 T492 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5567 T492 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5572 T492 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5576 T492 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5577 T492 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5578 T492 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-1348348619423/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/
[junit4:junit4]   2> 5579 T492 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@f7afd4b7
[junit4:junit4]   2> 5579 T492 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/index/
[junit4:junit4]   2> 5580 T492 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5581 T492 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-1348348614438/jetty3/index forceNew:false
[junit4:junit4]   2> 5584 T492 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5584 T492 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5585 T492 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5587 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5588 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5588 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5589 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5590 T492 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5590 T492 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5591 T492 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5591 T492 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5592 T492 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5593 T492 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5594 T492 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5594 T492 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5595 T492 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5595 T492 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5596 T492 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5597 T492 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5597 T492 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5598 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5599 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5600 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5600 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5601 T492 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5602 T492 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5603 T492 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5603 T492 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5604 T492 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5606 T492 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5607 T492 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> 5609 T492 oass.SolrIndexSearcher.<init> Opening Searcher@9801f14a main
[junit4:junit4]   2> 5611 T492 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5611 T492 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5612 T492 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5616 T492 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5638 T551 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9801f14a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5951 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5955 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5955 T550 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5955 T538 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5955 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6145 T492 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6146 T492 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56706/solr shardId:shard1
[junit4:junit4]   2> 6149 T492 oasc.ZkController.register We are http://127.0.0.1:56706/solr/collection1/ and leader is http://127.0.0.1:52960/solr/collection1/
[junit4:junit4]   2> 6150 T492 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56706/solr
[junit4:junit4]   2> 6151 T492 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 6151 T492 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C35 name=collection1 org.apache.solr.core.SolrCore@739d3cba url=http://127.0.0.1:56706/solr/collection1 node=127.0.0.1:56706_solr
[junit4:junit4]   2> 6154 T552 C35 P56706 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 6155 T552 C35 P56706 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 6154 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6156 T552 C35 P56706 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:52960/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 6156 T492 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 6157 T492 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6158 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6159 T552 C35 P56706 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 6164 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@21072678
[junit4:junit4]   2> 6164 T552 C35 P56706 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:56706/solr START replicas=[http://127.0.0.1:52960/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 6165 T552 C35 P56706 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 6166 T552 C35 P56706 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 6166 T552 C35 P56706 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 6167 T553 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 6167 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6167 T552 C35 P56706 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6171 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37352
[junit4:junit4]   2> 6172 T553 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 6172 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37352
[junit4:junit4]   2> ASYNC  NEW_CORE C36 name=collection1 org.apache.solr.core.SolrCore@c8916e67 url=http://127.0.0.1:52960/solr/collection1 node=127.0.0.1:52960_solr
[junit4:junit4]   2> 6173 T518 C36 P52960 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=1 
[junit4:junit4]   2> 6174 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0006 with negotiated timeout 10000 for client /127.0.0.1:37352
[junit4:junit4]   2> 6174 T553 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0006, negotiated timeout = 10000
[junit4:junit4]   2> 6174 T555 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@21072678 name:ZooKeeperConnection Watcher:127.0.0.1:44271/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6175 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6180 T492 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6184 T492 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 6185 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 6190 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6192 T492 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):1800
[junit4:junit4]   2> 6193 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6460 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6465 T538 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6465 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6465 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6466 T550 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6465 T555 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7194 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8196 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C35_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:56706/solr, state=recovering, node_name=127.0.0.1:56706_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 9182 T552 C35 P56706 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 9183 T552 C35 P56706 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 9183 T552 C35 P56706 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:52960/solr/collection1/. core=collection1
[junit4:junit4]   2> 9183 T552 C35 P56706 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9191 T520 C36 P52960 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 9194 T520 C36 P52960 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9196 T520 C36 P52960 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9196 T520 C36 P52960 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 9197 T520 C36 P52960 oass.SolrIndexSearcher.<init> Opening Searcher@ae28c725 main
[junit4:junit4]   2> 9198 T520 C36 P52960 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9198 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9200 T527 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ae28c725 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9200 T520 C36 P52960 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 11
[junit4:junit4]   2> 9201 T552 C35 P56706 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 9201 T552 C35 P56706 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 9207 T552 C35 P56706 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 9209 T521 C36 P52960 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 9210 T521 C36 P52960 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 9212 T552 C35 P56706 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9212 T552 C35 P56706 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 9213 T552 C35 P56706 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9221 T552 C35 P56706 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 9222 T552 C35 P56706 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 9223 T552 C35 P56706 oass.SolrIndexSearcher.<init> Opening Searcher@c914625c main
[junit4:junit4]   2> 9223 T552 C35 P56706 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9225 T551 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c914625c main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 9226 T552 C35 P56706 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 9226 T552 C35 P56706 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 9228 T552 C35 P56706 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 9475 T513 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9481 T538 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9481 T550 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9481 T526 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9481 T512 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9481 T555 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10200 T492 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C37 name=collection1 org.apache.solr.core.SolrCore@cd36712d url=http://127.0.0.1:36044/solr/collection1 node=127.0.0.1:36044_solr
[junit4:junit4]   2> 10215 T504 C37 P36044 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10216 T504 C37 P36044 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10238 T504 C37 P36044 oass.SolrIndexSearcher.<init> Opening Searcher@e804599e realtime
[junit4:junit4]   2> 10239 T504 C37 P36044 /update {version=2&wt=javabin} {deleteByQuery=*:* (-1413846007429464064)} 0 35
[junit4:junit4]   2> 10564 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@8e8beb8a
[junit4:junit4]   2> 10566 T556 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 10566 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10567 T556 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 10567 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37372
[junit4:junit4]   2> 10567 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37372
[junit4:junit4]   2> 10568 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0007 with negotiated timeout 10000 for client /127.0.0.1:37372
[junit4:junit4]   2> 10568 T556 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0007, negotiated timeout = 10000
[junit4:junit4]   2> 10569 T557 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8e8beb8a name:ZooKeeperConnection Watcher:127.0.0.1:44271/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10569 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10570 T492 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10574 T522 C36 P52960 oass.SolrIndexSearcher.<init> Opening Searcher@8b08ae30 realtime
[junit4:junit4]   2>  C35_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:56706/solr, state=active, node_name=127.0.0.1:56706_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 10581 T542 C35 P56706 oass.SolrIndexSearcher.<init> Opening Searcher@d655052e realtime
[junit4:junit4]   2> 10582 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&_version_=-1413846007816388608&version=2&wt=javabin&update.from=http://127.0.0.1:52960/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> ASYNC  NEW_CORE C38 name=collection1 org.apache.solr.core.SolrCore@a5991bd4 url=http://127.0.0.1:55260/solr/collection1 node=127.0.0.1:55260_solr
[junit4:junit4]   2> 10582 T530 C38 P55260 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10583 T530 C38 P55260 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10584 T530 C38 P55260 oass.SolrIndexSearcher.<init> Opening Searcher@e4cd5e4b realtime
[junit4:junit4]   2> 10584 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 10585 T522 C36 P52960 /update {version=2&wt=javabin} {deleteByQuery=*:* (-1413846007816388608)} 0 13
[junit4:junit4]   2> 10591 T504 C37 P36044 /update {version=2&wt=javabin} {add=[1 (1413846007831068672)]} 0 3
[junit4:junit4]   2> 10600 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 10600 T522 C36 P52960 /update {version=2&wt=javabin} {add=[1 (1413846007836311552)]} 0 7
[junit4:junit4]   2> 10604 T504 C37 P36044 /update {version=2&wt=javabin} {add=[2 (1413846007846797312)]} 0 2
[junit4:junit4]   2> 10608 T531 C38 P55260 /update {version=2&wt=javabin} {add=[2 (1413846007849943040)]} 0 2
[junit4:junit4]   2> 10611 T504 C37 P36044 /update {version=2&wt=javabin} {add=[3 (1413846007854137344)]} 0 1
[junit4:junit4]   2> 10617 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10618 T522 C36 P52960 /update {version=2&wt=javabin} {add=[3]} 0 5
[junit4:junit4]   2> 10621 T504 C37 P36044 /update {version=2&wt=javabin} {add=[4 (1413846007864623104)]} 0 1
[junit4:junit4]   2> 10627 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10628 T522 C36 P52960 /update {version=2&wt=javabin} {add=[4 (1413846007867768832)]} 0 5
[junit4:junit4]   2> 10631 T504 C37 P36044 /update {version=2&wt=javabin} {add=[5 (1413846007875108864)]} 0 1
[junit4:junit4]   2> 10636 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10637 T522 C36 P52960 /update {version=2&wt=javabin} {add=[5]} 0 4
[junit4:junit4]   2> 10640 T504 C37 P36044 /update {version=2&wt=javabin} {add=[6 (1413846007884546048)]} 0 1
[junit4:junit4]   2> 10643 T531 C38 P55260 /update {version=2&wt=javabin} {add=[6 (1413846007887691776)]} 0 1
[junit4:junit4]   2> 10647 T504 C37 P36044 /update {version=2&wt=javabin} {add=[7 (1413846007891886080)]} 0 2
[junit4:junit4]   2> 10652 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10653 T522 C36 P52960 /update {version=2&wt=javabin} {add=[7]} 0 4
[junit4:junit4]   2> 10656 T504 C37 P36044 /update {version=2&wt=javabin} {add=[8 (1413846007901323264)]} 0 1
[junit4:junit4]   2> 10664 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10665 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 10665 T531 C38 P55260 /update {version=2&wt=javabin} {add=[8]} 0 7
[junit4:junit4]   2> 10668 T504 C37 P36044 /update {version=2&wt=javabin} {add=[9 (1413846007913906176)]} 0 1
[junit4:junit4]   2> 10672 T531 C38 P55260 /update {version=2&wt=javabin} {add=[9 (1413846007918100480)]} 0 1
[junit4:junit4]   2> 10685 T504 C37 P36044 /update {version=2&wt=javabin} {add=[10 (1413846007921246208)]} 0 11
[junit4:junit4]   2> 10693 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10694 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 10695 T531 C38 P55260 /update {version=2&wt=javabin} {add=[10]} 0 8
[junit4:junit4]   2> 10699 T504 C37 P36044 /update {version=2&wt=javabin} {add=[11 (1413846007945363456)]} 0 2
[junit4:junit4]   2> 10707 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10707 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 10708 T531 C38 P55260 /update {version=2&wt=javabin} {add=[11]} 0 7
[junit4:junit4]   2> 10712 T504 C37 P36044 /update {version=2&wt=javabin} {add=[12 (1413846007958994944)]} 0 2
[junit4:junit4]   2> 10718 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10719 T522 C36 P52960 /update {version=2&wt=javabin} {add=[12 (1413846007963189248)]} 0 5
[junit4:junit4]   2> 10722 T504 C37 P36044 /update {version=2&wt=javabin} {add=[13 (1413846007970529280)]} 0 1
[junit4:junit4]   2> 10729 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10730 T522 C36 P52960 /update {version=2&wt=javabin} {add=[13 (1413846007974723584)]} 0 6
[junit4:junit4]   2> 10733 T504 C37 P36044 /update {version=2&wt=javabin} {add=[14 (1413846007982063616)]} 0 1
[junit4:junit4]   2> 10742 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 10742 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 10743 T531 C38 P55260 /update {version=2&wt=javabin} {add=[14]} 0 8
[junit4:junit4]   2> 10747 T504 C37 P36044 /update {version=2&wt=javabin} {add=[15 (1413846007996743680)]} 0 1
[junit4:junit4]   2> 10754 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 10754 T522 C36 P52960 /update {version=2&wt=javabin} {add=[15 (1413846007999889408)]} 0 5
[junit4:junit4]   2> 10758 T504 C37 P36044 /update {version=2&wt=javabin} {add=[16 (1413846008008278016)]} 0 1
[junit4:junit4]   2> 10792 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=15 
[junit4:junit4]   2> 10792 T522 C36 P52960 /update {version=2&wt=javabin} {add=[16 (1413846008011423744)]} 0 32
[junit4:junit4]   2> 10798 T504 C37 P36044 /update {version=2&wt=javabin} {add=[17 (1413846008049172480)]} 0 3
[junit4:junit4]   2> 10805 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 10806 T522 C36 P52960 /update {version=2&wt=javabin} {add=[17]} 0 6
[junit4:junit4]   2> 10809 T504 C37 P36044 /update {version=2&wt=javabin} {add=[100 (1413846008061755392)]} 0 1
[junit4:junit4]   2> 10829 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 10829 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=15 
[junit4:junit4]   2> 10830 T531 C38 P55260 /update {version=2&wt=javabin} {add=[100]} 0 18
[junit4:junit4]   2> 10834 T504 C37 P36044 /update {version=2&wt=javabin} {add=[101 (1413846008087969792)]} 0 2
[junit4:junit4]   2> 10842 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10843 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 10843 T531 C38 P55260 /update {version=2&wt=javabin} {add=[101]} 0 7
[junit4:junit4]   2> 10857 T504 C37 P36044 /update {version=2&wt=javabin} {add=[102 (1413846008101601280)]} 0 12
[junit4:junit4]   2> 10866 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 10867 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=6 
[junit4:junit4]   2> 10868 T531 C38 P55260 /update {version=2&wt=javabin} {add=[102]} 0 9
[junit4:junit4]   2> 10873 T504 C37 P36044 /update {version=2&wt=javabin} {add=[103 (1413846008127815680)]} 0 2
[junit4:junit4]   2> 10876 T531 C38 P55260 /update {version=2&wt=javabin} {add=[103 (1413846008132009984)]} 0 1
[junit4:junit4]   2> 10880 T504 C37 P36044 /update {version=2&wt=javabin} {add=[104 (1413846008136204288)]} 0 1
[junit4:junit4]   2> 10884 T531 C38 P55260 /update {version=2&wt=javabin} {add=[104 (1413846008140398592)]} 0 2
[junit4:junit4]   2> 10887 T504 C37 P36044 /update {version=2&wt=javabin} {add=[105 (1413846008143544320)]} 0 1
[junit4:junit4]   2> 10902 T531 C38 P55260 /update {version=2&wt=javabin} {add=[105 (1413846008147738624)]} 0 12
[junit4:junit4]   2> 10906 T504 C37 P36044 /update {version=2&wt=javabin} {add=[106 (1413846008163467264)]} 0 1
[junit4:junit4]   2> 10911 T531 C38 P55260 /update {version=2&wt=javabin} {add=[106 (1413846008167661568)]} 0 2
[junit4:junit4]   2> 10916 T504 C37 P36044 /update {version=2&wt=javabin} {add=[107 (1413846008173953024)]} 0 2
[junit4:junit4]   2> 10923 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 10923 T522 C36 P52960 /update {version=2&wt=javabin} {add=[107 (1413846008178147328)]} 0 5
[junit4:junit4]   2> 10927 T504 C37 P36044 /update {version=2&wt=javabin} {add=[108 (1413846008185487360)]} 0 1
[junit4:junit4]   2> 10934 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10935 T522 C36 P52960 /update {version=2&wt=javabin} {add=[108 (1413846008190730240)]} 0 4
[junit4:junit4]   2> 10939 T504 C37 P36044 /update {version=2&wt=javabin} {add=[109 (1413846008198070272)]} 0 1
[junit4:junit4]   2> 10945 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10946 T522 C36 P52960 /update {version=2&wt=javabin} {add=[109 (1413846008201216000)]} 0 5
[junit4:junit4]   2> 10949 T504 C37 P36044 /update {version=2&wt=javabin} {add=[110 (1413846008209604608)]} 0 1
[junit4:junit4]   2> 10956 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10957 T522 C36 P52960 /update {version=2&wt=javabin} {add=[110 (1413846008212750336)]} 0 5
[junit4:junit4]   2> 10960 T504 C37 P36044 /update {version=2&wt=javabin} {add=[111 (1413846008220090368)]} 0 1
[junit4:junit4]   2> 10966 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10967 T522 C36 P52960 /update {version=2&wt=javabin} {add=[111 (1413846008224284672)]} 0 4
[junit4:junit4]   2> 10983 T504 C37 P36044 /update {version=2&wt=javabin} {add=[112 (1413846008231624704)]} 0 13
[junit4:junit4]   2> 10988 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 10989 T522 C36 P52960 /update {version=2&wt=javabin} {add=[112]} 0 4
[junit4:junit4]   2> 10994 T504 C37 P36044 /update {version=2&wt=javabin} {add=[113 (1413846008254693376)]} 0 2
[junit4:junit4]   2> 11000 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11000 T522 C36 P52960 /update {version=2&wt=javabin} {add=[113 (1413846008258887680)]} 0 4
[junit4:junit4]   2> 11004 T504 C37 P36044 /update {version=2&wt=javabin} {add=[114 (1413846008266227712)]} 0 1
[junit4:junit4]   2> 11036 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=16 
[junit4:junit4]   2> 11037 T522 C36 P52960 /update {version=2&wt=javabin} {add=[114 (1413846008270422016)]} 0 30
[junit4:junit4]   2> 11041 T504 C37 P36044 /update {version=2&wt=javabin} {add=[115 (1413846008305025024)]} 0 1
[junit4:junit4]   2> 11047 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11048 T522 C36 P52960 /update {version=2&wt=javabin} {add=[115]} 0 4
[junit4:junit4]   2> 11053 T504 C37 P36044 /update {version=2&wt=javabin} {add=[116 (1413846008316559360)]} 0 2
[junit4:junit4]   2> 11057 T531 C38 P55260 /update {version=2&wt=javabin} {add=[116 (1413846008321802240)]} 0 2
[junit4:junit4]   2> 11061 T504 C37 P36044 /update {version=2&wt=javabin} {add=[117 (1413846008325996544)]} 0 1
[junit4:junit4]   2> 11067 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11068 T522 C36 P52960 /update {version=2&wt=javabin} {add=[117]} 0 4
[junit4:junit4]   2> 11071 T504 C37 P36044 /update {version=2&wt=javabin} {add=[118 (1413846008336482304)]} 0 1
[junit4:junit4]   2> 11081 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 11082 T522 C36 P52960 /update {version=2&wt=javabin} {add=[118 (1413846008340676608)]} 0 9
[junit4:junit4]   2> 11087 T504 C37 P36044 /update {version=2&wt=javabin} {add=[119 (1413846008352210944)]} 0 2
[junit4:junit4]   2> 11091 T531 C38 P55260 /update {version=2&wt=javabin} {add=[119 (1413846008357453824)]} 0 1
[junit4:junit4]   2> 11095 T504 C37 P36044 /update {version=2&wt=javabin} {add=[120 (1413846008361648128)]} 0 1
[junit4:junit4]   2> 11100 T531 C38 P55260 /update {version=2&wt=javabin} {add=[120 (1413846008366891008)]} 0 2
[junit4:junit4]   2> 11105 T504 C37 P36044 /update {version=2&wt=javabin} {add=[121 (1413846008372133888)]} 0 1
[junit4:junit4]   2> 11112 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11113 T522 C36 P52960 /update {version=2&wt=javabin} {add=[121]} 0 5
[junit4:junit4]   2> 11131 T504 C37 P36044 /update {version=2&wt=javabin} {add=[122 (1413846008384716800)]} 0 15
[junit4:junit4]   2> 11139 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11140 T522 C36 P52960 /update {version=2&wt=javabin} {add=[122 (1413846008403591168)]} 0 6
[junit4:junit4]   2> 11145 T504 C37 P36044 /update {version=2&wt=javabin} {add=[123 (1413846008413028352)]} 0 2
[junit4:junit4]   2> 11153 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11155 T522 C36 P52960 /update {version=2&wt=javabin} {add=[123 (1413846008419319808)]} 0 7
[junit4:junit4]   2> 11159 T504 C37 P36044 /update {version=2&wt=javabin} {add=[124 (1413846008428756992)]} 0 1
[junit4:junit4]   2> 11168 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11168 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 11169 T531 C38 P55260 /update {version=2&wt=javabin} {add=[124]} 0 7
[junit4:junit4]   2> 11172 T504 C37 P36044 /update {version=2&wt=javabin} {add=[125 (1413846008442388480)]} 0 1
[junit4:junit4]   2> 11178 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11179 T522 C36 P52960 /update {version=2&wt=javabin} {add=[125 (1413846008445534208)]} 0 5
[junit4:junit4]   2> 11182 T504 C37 P36044 /update {version=2&wt=javabin} {add=[126 (1413846008452874240)]} 0 1
[junit4:junit4]   2> 11186 T531 C38 P55260 /update {version=2&wt=javabin} {add=[126 (1413846008457068544)]} 0 2
[junit4:junit4]   2> 11189 T504 C37 P36044 /update {version=2&wt=javabin} {add=[127 (1413846008460214272)]} 0 1
[junit4:junit4]   2> 11205 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=11 
[junit4:junit4]   2> 11206 T522 C36 P52960 /update {version=2&wt=javabin} {add=[127]} 0 14
[junit4:junit4]   2> 11210 T504 C37 P36044 /update {version=2&wt=javabin} {add=[128 (1413846008482234368)]} 0 1
[junit4:junit4]   2> 11214 T531 C38 P55260 /update {version=2&wt=javabin} {add=[128 (1413846008485380096)]} 0 2
[junit4:junit4]   2> 11218 T504 C37 P36044 /update {version=2&wt=javabin} {add=[129 (1413846008490622976)]} 0 1
[junit4:junit4]   2> 11225 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11226 T522 C36 P52960 /update {version=2&wt=javabin} {add=[129 (1413846008494817280)]} 0 6
[junit4:junit4]   2> 11230 T504 C37 P36044 /update {version=2&wt=javabin} {add=[130 (1413846008503205888)]} 0 1
[junit4:junit4]   2> 11238 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 11239 T522 C36 P52960 /update {version=2&wt=javabin} {add=[130]} 0 6
[junit4:junit4]   2> 11243 T504 C37 P36044 /update {version=2&wt=javabin} {add=[131 (1413846008516837376)]} 0 1
[junit4:junit4]   2> 11247 T531 C38 P55260 /update {version=2&wt=javabin} {add=[131 (1413846008521031680)]} 0 1
[junit4:junit4]   2> 11261 T504 C37 P36044 /update {version=2&wt=javabin} {add=[132 (1413846008526274560)]} 0 11
[junit4:junit4]   2> 11270 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11271 T522 C36 P52960 /update {version=2&wt=javabin} {add=[132 (1413846008539906048)]} 0 7
[junit4:junit4]   2> 11277 T504 C37 P36044 /update {version=2&wt=javabin} {add=[133 (1413846008550391808)]} 0 3
[junit4:junit4]   2> 11287 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11288 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=6 
[junit4:junit4]   2> 11288 T531 C38 P55260 /update {version=2&wt=javabin} {add=[133]} 0 8
[junit4:junit4]   2> 11292 T504 C37 P36044 /update {version=2&wt=javabin} {add=[134 (1413846008568217600)]} 0 1
[junit4:junit4]   2> 11297 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11298 T522 C36 P52960 /update {version=2&wt=javabin} {add=[134]} 0 4
[junit4:junit4]   2> 11302 T504 C37 P36044 /update {version=2&wt=javabin} {add=[135 (1413846008578703360)]} 0 1
[junit4:junit4]   2> 11310 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11311 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11312 T531 C38 P55260 /update {version=2&wt=javabin} {add=[135]} 0 8
[junit4:junit4]   2> 11317 T504 C37 P36044 /update {version=2&wt=javabin} {add=[136 (1413846008594432000)]} 0 1
[junit4:junit4]   2> 11323 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11324 T522 C36 P52960 /update {version=2&wt=javabin} {add=[136]} 0 4
[junit4:junit4]   2> 11329 T504 C37 P36044 /update {version=2&wt=javabin} {add=[137 (1413846008605966336)]} 0 2
[junit4:junit4]   2> 11335 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11336 T522 C36 P52960 /update {version=2&wt=javabin} {add=[137]} 0 4
[junit4:junit4]   2> 11340 T504 C37 P36044 /update {version=2&wt=javabin} {add=[138 (1413846008618549248)]} 0 1
[junit4:junit4]   2> 11372 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=11 
[junit4:junit4]   2> 11373 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=28 
[junit4:junit4]   2> 11374 T531 C38 P55260 /update {version=2&wt=javabin} {add=[138]} 0 31
[junit4:junit4]   2> 11378 T504 C37 P36044 /update {version=2&wt=javabin} {add=[139 (1413846008658395136)]} 0 1
[junit4:junit4]   2> 11382 T531 C38 P55260 /update {version=2&wt=javabin} {add=[139 (1413846008662589440)]} 0 1
[junit4:junit4]   2> 11387 T504 C37 P36044 /update {version=2&wt=javabin} {add=[140 (1413846008667832320)]} 0 2
[junit4:junit4]   2> 11393 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11394 T522 C36 P52960 /update {version=2&wt=javabin} {add=[140]} 0 5
[junit4:junit4]   2> 11398 T504 C37 P36044 /update {version=2&wt=javabin} {add=[141 (1413846008679366656)]} 0 1
[junit4:junit4]   2> 11402 T531 C38 P55260 /update {version=2&wt=javabin} {add=[141 (1413846008683560960)]} 0 1
[junit4:junit4]   2> 11415 T504 C37 P36044 /update {version=2&wt=javabin} {add=[142 (1413846008687755264)]} 0 10
[junit4:junit4]   2> 11427 T531 C38 P55260 /update {version=2&wt=javabin} {add=[142 (1413846008701386752)]} 0 9
[junit4:junit4]   2> 11432 T504 C37 P36044 /update {version=2&wt=javabin} {add=[143 (1413846008713969664)]} 0 2
[junit4:junit4]   2> 11438 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11438 T522 C36 P52960 /update {version=2&wt=javabin} {add=[143]} 0 4
[junit4:junit4]   2> 11442 T504 C37 P36044 /update {version=2&wt=javabin} {add=[144 (1413846008725504000)]} 0 1
[junit4:junit4]   2> 11446 T531 C38 P55260 /update {version=2&wt=javabin} {add=[144 (1413846008729698304)]} 0 1
[junit4:junit4]   2> 11450 T504 C37 P36044 /update {version=2&wt=javabin} {add=[145 (1413846008733892608)]} 0 1
[junit4:junit4]   2> 11455 T531 C38 P55260 /update {version=2&wt=javabin} {add=[145 (1413846008739135488)]} 0 2
[junit4:junit4]   2> 11459 T504 C37 P36044 /update {version=2&wt=javabin} {add=[146 (1413846008743329792)]} 0 1
[junit4:junit4]   2> 11471 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11472 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=8 
[junit4:junit4]   2> 11473 T531 C38 P55260 /update {version=2&wt=javabin} {add=[146]} 0 11
[junit4:junit4]   2> 11477 T504 C37 P36044 /update {version=2&wt=javabin} {add=[147 (1413846008762204160)]} 0 2
[junit4:junit4]   2> 11483 T530 C38 P55260 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11484 T522 C36 P52960 /update {version=2&wt=javabin} {add=[147]} 0 5
[junit4:junit4]   2> 11487 T504 C37 P36044 /update {version=2&wt=javabin} {add=[148 (1413846008772689920)]} 0 1
[junit4:junit4]   2> 11496 T542 C35 P56706 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52960/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 11497 T523 C36 P52960 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:55260/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 11498 T531 C38 P55260 /update {version=2&wt=javabin} {add=[148]} 0 8
[junit4:junit4]   2> 11502 T504 C37 P36044 /update {version=2&wt=javabin} {add=[149 (1413846008788418560)]} 0 1
[junit4:junit4]   2> 11507 T531 C38 P55260 /update {version=2&wt=javabin} {add=[149 (1413846008793661440)]} 0 2
[junit4:junit4]   2> 11510 T504 C37 P36044 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11611 T504 C37 P36044 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/control/data/index,segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _1_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _3_TestBloomFilteredLucene40Postings_0.tim, _1_TestBloomFilteredLucene40Postings_0.tip, _4_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tip, _5_TestBloomFilteredLucene40Postings_0.tim, _6_TestBloomFilteredLucene40Postings_0.tim, _3_TestBloomFilteredLucene40Postings_0.tip, _4_TestBloomFilteredLucene40Postings_0.tip, _5_TestBloomFilteredLucene40Postings_0.tip, _6_TestBloomFilteredLucene40Postings_0.tip, _1.si, _3_SimpleText_0.pst, _0_TestBloomFilteredLucene40Postings_0.prx, _1_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _3_TestBloomFilteredLucene40Postings_0.prx, _4_TestBloomFilteredLucene40Postings_0.prx, _5_TestBloomFilteredLucene40Postings_0.prx, _6_TestBloomFilteredLucene40Postings_0.prx, _4.si, _1.fdt, _3.fdt, _4_SimpleText_0.pst, _5.fdt, _1.fdx, _3.fdx, _5.fdx, _1_Lucene40_0.tim, _1_Lucene40_0.tip, _0_Pulsing40_0.frq, _1_Pulsing40_0.frq, _2_Pulsing40_0.frq, _3_Pulsing40_0.frq, _4_Pulsing40_0.frq, _5_Pulsing40_0.frq, _6_Pulsing40_0.frq, segments_2, _5_SimpleText_0.pst, _1_Lucene40_0.prx, _0_Lucene40_0.tim, _1.fnm, _0_Lucene40_0.tip, _3.fnm, _5.fnm, _2.si, _6_SimpleText_0.pst, _0_Lucene40_0.prx, _5.si, _0_nrm.cfe, _1_nrm.cfe, _2_nrm.cfe, _0_TestBloomFilteredLucene40Postings_0.frq, _1_TestBloomFilteredLucene40Postings_0.frq, _3_nrm.cfe, _2_TestBloomFilteredLucene40Postings_0.frq, _4_nrm.cfe, _5_nrm.cfe, _3_TestBloomFilteredLucene40Postings_0.frq, _6_nrm.cfe, _4_TestBloomFilteredLucene40Postings_0.frq, _5_TestBloomFilteredLucene40Postings_0.frq, _6_TestBloomFilteredLucene40Postings_0.frq, _0_nrm.cfs, _1_nrm.cfs, _2_nrm.cfs, _3_nrm.cfs, _4_nrm.cfs, _5_nrm.cfs, _6_nrm.cfs, _0.si, _0.fdt, _2.fdt, _0.fdx, _4.fdt, _6.fdt, _0_Pulsing40_0.tim, _2.fdx, _1_Pulsing40_0.tim, _2_Pulsing40_0.tim, _0_SimpleText_0.pst, _4.fdx, _0_Pulsing40_0.tip, _3_Pulsing40_0.tim, _6.fdx, _1_Pulsing40_0.tip, _4_Pulsing40_0.tim, _2_Pulsing40_0.tip, _5_Pulsing40_0.tim, _6_Pulsing40_0.tim, _3_Pulsing40_0.tip, _4_Pulsing40_0.tip, _5_Pulsing40_0.tip, _6_Pulsing40_0.tip, _0_TestBloomFilteredLucene40Postings_0.blm, _1_TestBloomFilteredLucene40Postings_0.blm, _3.si, _2_TestBloomFilteredLucene40Postings_0.blm, _3_TestBloomFilteredLucene40Postings_0.blm, _1_Lucene40_0.frq, _4_TestBloomFilteredLucene40Postings_0.blm, _5_TestBloomFilteredLucene40Postings_0.blm, _6_TestBloomFilteredLucene40Postings_0.blm, _0_Pulsing40_0.prx, _1_Pulsing40_0.prx, _2_Pulsing40_0.prx, _1_SimpleText_0.pst, _3_Pulsing40_0.prx, _4_Pulsing40_0.prx, _5_Pulsing40_0.prx, _6_Pulsing40_0.prx, _0.fnm, _2.fnm, _4.fnm, _6.si, _6.fnm, _0_Lucene40_0.frq, _2_SimpleText_0.pst]
[junit4:junit4]   2> 11612 T504 C37 P36044 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11616 T504 C37 P36044 oass.SolrIndexSearcher.<init> Opening Searcher@3af441ee main
[junit4:junit4]   2> 11617 T504 C37 P36044 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11620 T515 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3af441ee 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> 11620 T504 C37 P36044 /update {version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 110
[junit4:junit4]   2> 11623 T522 C36 P52960 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11691 T522 C36 P52960 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty1/index,segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_Lucene40_0.prx, _1_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _1_TestBloomFilteredLucene40Postings_0.tip, _2_TestBloomFilteredLucene40Postings_0.tip, _3_TestBloomFilteredLucene40Postings_0.tip, _0_nrm.cfe, _1_nrm.cfe, _0_TestBloomFilteredLucene40Postings_0.frq, _2_nrm.cfe, _3_nrm.cfe, _1_TestBloomFilteredLucene40Postings_0.frq, _1.si, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.frq, _0_nrm.cfs, _3_SimpleText_0.pst, _1_nrm.cfs, _2_nrm.cfs, _3_nrm.cfs, _0_TestBloomFilteredLucene40Postings_0.prx, _1_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _3_TestBloomFilteredLucene40Postings_0.prx, _0.si, _0.fdt, _1.fdt, _2.fdt, _3.fdt, _0.fdx, _1.fdx, _2.fdx, _0_Pulsing40_0.tim, _3.fdx, _1_Pulsing40_0.tim, _0_SimpleText_0.pst, _2_Pulsing40_0.tim, _3_Pulsing40_0.tim, _0_Pulsing40_0.tip, _1_Pulsing40_0.tip, _2_Pulsing40_0.tip, _3_Pulsing40_0.tip, _0_Pulsing40_0.frq, _1_Pulsing40_0.frq, _2_Pulsing40_0.frq, _3_Pulsing40_0.frq, _0_TestBloomFilteredLucene40Postings_0.blm, _3.si, _1_TestBloomFilteredLucene40Postings_0.blm, _2_TestBloomFilteredLucene40Postings_0.blm, _3_TestBloomFilteredLucene40Postings_0.blm, segments_2, _0_Pulsing40_0.prx, _1_Pulsing40_0.prx, _2_Pulsing40_0.prx, _3_Pulsing40_0.prx, _1_SimpleText_0.pst, _0_Lucene40_0.tim, _0.fnm, _1.fnm, _0_Lucene40_0.tip, _2.fnm, _3.fnm, _2.si, _0_Lucene40_0.frq, _2_SimpleText_0.pst]
[junit4:junit4]   2> 11692 T522 C36 P52960 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11695 T522 C36 P52960 oass.SolrIndexSearcher.<init> Opening Searcher@42e0b2ee main
[junit4:junit4]   2> 11696 T522 C36 P52960 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11698 T527 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@42e0b2ee 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> 11699 T522 C36 P52960 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:56706/solr/collection1/, StdNode: http://127.0.0.1:55260/solr/collection1/]
[junit4:junit4]   2> 11701 T542 C35 P56706 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11701 T530 C38 P55260 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11771 T530 C38 P55260 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty2/index,segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_Lucene40_0.prx, _1_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _1_TestBloomFilteredLucene40Postings_0.tip, _2_TestBloomFilteredLucene40Postings_0.tip, _3_TestBloomFilteredLucene40Postings_0.tip, _0_nrm.cfe, _1_nrm.cfe, _0_TestBloomFilteredLucene40Postings_0.frq, _2_nrm.cfe, _3_nrm.cfe, _1_TestBloomFilteredLucene40Postings_0.frq, _1.si, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.frq, _0_nrm.cfs, _3_SimpleText_0.pst, _1_nrm.cfs, _2_nrm.cfs, _3_nrm.cfs, _0_TestBloomFilteredLucene40Postings_0.prx, _1_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _3_TestBloomFilteredLucene40Postings_0.prx, _0.si, _0.fdt, _1.fdt, _2.fdt, _3.fdt, _0.fdx, _1.fdx, _2.fdx, _0_Pulsing40_0.tim, _3.fdx, _1_Pulsing40_0.tim, _0_SimpleText_0.pst, _2_Pulsing40_0.tim, _3_Pulsing40_0.tim, _0_Pulsing40_0.tip, _1_Pulsing40_0.tip, _2_Pulsing40_0.tip, _3_Pulsing40_0.tip, _0_Pulsing40_0.frq, _1_Pulsing40_0.frq, _2_Pulsing40_0.frq, _3_Pulsing40_0.frq, _0_TestBloomFilteredLucene40Postings_0.blm, _3.si, _1_TestBloomFilteredLucene40Postings_0.blm, _2_TestBloomFilteredLucene40Postings_0.blm, _3_TestBloomFilteredLucene40Postings_0.blm, segments_2, _0_Pulsing40_0.prx, _1_Pulsing40_0.prx, _2_Pulsing40_0.prx, _3_Pulsing40_0.prx, _1_SimpleText_0.pst, _0_Lucene40_0.tim, _0.fnm, _1.fnm, _0_Lucene40_0.tip, _2.fnm, _3.fnm, _2.si, _0_Lucene40_0.frq, _2_SimpleText_0.pst]
[junit4:junit4]   2> 11772 T530 C38 P55260 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11773 T542 C35 P56706 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1348348614438/jetty3/index,segFN=segments_3,generation=3,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_Lucene40_0.prx, _1_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _3_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _1_TestBloomFilteredLucene40Postings_0.tip, _2_TestBloomFilteredLucene40Postings_0.tip, _3_TestBloomFilteredLucene40Postings_0.tip, _0_nrm.cfe, _1_nrm.cfe, _0_TestBloomFilteredLucene40Postings_0.frq, _2_nrm.cfe, _3_nrm.cfe, _1_TestBloomFilteredLucene40Postings_0.frq, _1.si, _2_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.frq, _0_nrm.cfs, _3_SimpleText_0.pst, _1_nrm.cfs, _2_nrm.cfs, _3_nrm.cfs, _0_TestBloomFilteredLucene40Postings_0.prx, _1_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.prx, _3_TestBloomFilteredLucene40Postings_0.prx, _0.si, _0.fdt, _1.fdt, _2.fdt, _3.fdt, _0.fdx, _1.fdx, _2.fdx, _0_Pulsing40_0.tim, _3.fdx, _1_Pulsing40_0.tim, _0_SimpleText_0.pst, _2_Pulsing40_0.tim, _3_Pulsing40_0.tim, _0_Pulsing40_0.tip, _1_Pulsing40_0.tip, _2_Pulsing40_0.tip, _3_Pulsing40_0.tip, _0_Pulsing40_0.frq, _1_Pulsing40_0.frq, _2_Pulsing40_0.frq, _3_Pulsing40_0.frq, _0_TestBloomFilteredLucene40Postings_0.blm, _3.si, _1_TestBloomFilteredLucene40Postings_0.blm, _2_TestBloomFilteredLucene40Postings_0.blm, _3_TestBloomFilteredLucene40Postings_0.blm, segments_3, _0_Pulsing40_0.prx, _1_Pulsing40_0.prx, _2_Pulsing40_0.prx, _3_Pulsing40_0.prx, _1_SimpleText_0.pst, _0_Lucene40_0.tim, _0.fnm, _1.fnm, _0_Lucene40_0.tip, _2.fnm, _3.fnm, _2.si, _0_Lucene40_0.frq, _2_SimpleText_0.pst]
[junit4:junit4]   2> 11773 T542 C35 P56706 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 11775 T530 C38 P55260 oass.SolrIndexSearcher.<init> Opening Searcher@59e23829 main
[junit4:junit4]   2> 11776 T530 C38 P55260 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11777 T542 C35 P56706 oass.SolrIndexSearcher.<init> Opening Searcher@984c6900 main
[junit4:junit4]   2> 11778 T542 C35 P56706 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11779 T539 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@59e23829 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> 11779 T530 C38 P55260 /update {softCommit=false&commit_end_point=true&expungeDeletes=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 78
[junit4:junit4]   2> 11780 T551 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@984c6900 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> 11781 T542 C35 P56706 /update {softCommit=false&commit_end_point=true&expungeDeletes=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 80
[junit4:junit4]   2> 11782 T522 C36 P52960 /update {version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 159
[junit4:junit4]   2> 11789 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_ti1+desc} hits=67 status=0 QTime=4 
[junit4:junit4]   2> 11791 T492 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 11793 T492 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44271/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@350d24c2
[junit4:junit4]   2> 11795 T561 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44271
[junit4:junit4]   2> 11795 T492 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 11796 T561 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:44271, initiating session
[junit4:junit4]   2> 11796 T494 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:37388
[junit4:junit4]   2> 11797 T494 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:37388
[junit4:junit4]   2> 11797 T496 oazs.NIOServerCnxn.finishSessionInit Established session 0x139efd7172a0008 with negotiated timeout 10000 for client /127.0.0.1:37388
[junit4:junit4]   2> 11797 T561 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:44271, sessionid = 0x139efd7172a0008, negotiated timeout = 10000
[junit4:junit4]   2> 11798 T562 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@350d24c2 name:ZooKeeperConnection Watcher:127.0.0.1:44271/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 11798 T492 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 11799 T492 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 11806 T519 C36 P52960 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626236&version=2&rows=10&wt=javabin&sort=n_ti1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 11807 T532 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626236&version=2&rows=10&wt=javabin&sort=n_ti1+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=3 
[junit4:junit4]   2> 11812 T532 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626236&ids=145,134,105,106,130,131&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11812 T519 C36 P52960 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626236&ids=132,146,4,118&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11814 T524 C36 P52960 REQ /select {version=2&q=*:*&wt=javabin&sort=n_ti1+desc} hits=67 status=0 QTime=12 
[junit4:junit4]   2> 11818 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_ti1+asc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11824 T520 C36 P52960 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626255&version=2&rows=10&wt=javabin&sort=n_ti1+asc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 11824 T534 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626255&version=2&rows=10&wt=javabin&sort=n_ti1+asc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 11829 T534 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626255&ids=121,127,117,119,120&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11829 T520 C36 P52960 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626255&ids=133,123,15,148,109&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11831 T533 C38 P55260 REQ /select {version=2&q=*:*&wt=javabin&sort=n_ti1+asc} hits=67 status=0 QTime=10 
[junit4:junit4]   2> 11836 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_f1+desc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11843 T544 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626274&version=2&rows=10&wt=javabin&collection=collection1&sort=n_f1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 11843 T535 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626274&version=2&rows=10&wt=javabin&collection=collection1&sort=n_f1+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 11849 T544 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626274&ids=110,129,108&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11849 T535 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626274&ids=144,112,134,145,147,106,141&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11851 T543 C35 P56706 REQ /select {version=2&q=*:*&wt=javabin&sort=n_f1+desc&collection=collection1} hits=67 status=0 QTime=11 
[junit4:junit4]   2> 11855 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_f1+asc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11861 T535 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626293&version=2&rows=10&wt=javabin&sort=n_f1+asc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 11861 T544 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626293&version=2&rows=10&wt=javabin&sort=n_f1+asc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 11866 T544 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626293&ids=122,12,123,124,109&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11866 T535 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626293&ids=105,149,127,130,142&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11869 T543 C35 P56706 REQ /select {version=2&q=*:*&wt=javabin&sort=n_f1+asc} hits=67 status=0 QTime=10 
[junit4:junit4]   2> 11893 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_tf1+desc} hits=67 status=0 QTime=21 
[junit4:junit4]   2> 11899 T545 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626331&version=2&rows=10&wt=javabin&sort=n_tf1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 11899 T532 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626331&version=2&rows=10&wt=javabin&sort=n_tf1+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 11903 T545 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626331&ids=110,125,107&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11906 T532 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626331&ids=2,103,126,149,7,9,130&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11908 T522 C36 P52960 REQ /select {version=2&q=*:*&wt=javabin&sort=n_tf1+desc} hits=67 status=0 QTime=11 
[junit4:junit4]   2> 11912 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_tf1+asc} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11918 T570 C36 P52960 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626349&version=2&rows=10&wt=javabin&collection=collection1&sort=n_tf1+asc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 11918 T535 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626349&version=2&rows=10&wt=javabin&sort=n_tf1+asc&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 11923 T535 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626349&ids=134,115,106,141,142&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11923 T570 C36 P52960 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626349&ids=122,13,146,8,108&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11925 T546 C35 P56706 REQ /select {version=2&q=*:*&wt=javabin&sort=n_tf1+asc&collection=collection1} hits=67 status=0 QTime=10 
[junit4:junit4]   2> 11929 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_d1+desc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11934 T545 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626366&version=2&rows=10&wt=javabin&sort=n_d1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 11934 T532 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626366&version=2&rows=10&wt=javabin&sort=n_d1+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 11938 T532 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626366&ids=128,106,119,141,131&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11938 T545 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626366&ids=102,146,114,16,108&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11940 T524 C36 P52960 REQ /select {version=2&q=*:*&wt=javabin&sort=n_d1+desc} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 11943 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_d1+asc} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11949 T534 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626380&version=2&rows=10&wt=javabin&collection=collection1&sort=n_d1+asc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 11949 T520 C36 P52960 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626380&version=2&rows=10&wt=javabin&collection=collection1&sort=n_d1+asc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 11953 T520 C36 P52960 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626380&ids=110,125,8,129,109&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11953 T534 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626380&ids=121,127,6,116,117&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11954 T531 C38 P55260 REQ /select {version=2&q=*:*&wt=javabin&sort=n_d1+asc&collection=collection1} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 11958 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_td1+desc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11965 T534 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626396&version=2&rows=10&wt=javabin&sort=n_td1+desc&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 11965 T547 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626396&version=2&rows=10&wt=javabin&collection=collection1&sort=n_td1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 11968 T547 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626396&ids=110,12,133,122,100,113&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11969 T534 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626396&ids=103,147,105,116&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11970 T533 C38 P55260 REQ /select {version=2&q=*:*&wt=javabin&sort=n_td1+desc&collection=collection1} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 11973 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_td1+asc} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11979 T532 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626411&version=2&rows=10&wt=javabin&sort=n_td1+asc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 11979 T545 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626411&version=2&rows=10&wt=javabin&sort=n_td1+asc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 11983 T532 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626411&ids=134,3,137,5,128,7&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11983 T545 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626411&ids=11,111,14,15&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 11985 T522 C36 P52960 REQ /select {version=2&q=*:*&wt=javabin&sort=n_td1+asc} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 11988 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_l1+desc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11994 T532 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626425&version=2&rows=10&wt=javabin&collection=collection1&sort=n_l1+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 11994 T519 C36 P52960 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626425&version=2&rows=10&wt=javabin&collection=collection1&sort=n_l1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 11997 T532 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626425&ids=121,145,112,137,115,130,131&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 11997 T519 C36 P52960 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626425&ids=13,15,138&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12000 T522 C36 P52960 REQ /select {version=2&q=*:*&wt=javabin&sort=n_l1+desc&collection=collection1} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 12004 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_l1+asc} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12009 T534 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626441&version=2&rows=10&wt=javabin&sort=n_l1+asc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 12009 T547 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626441&version=2&rows=10&wt=javabin&sort=n_l1+asc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 12012 T534 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626441&ids=127,128,7&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12013 T547 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626441&ids=132,100,113,114,129,109,10&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12015 T531 C38 P55260 REQ /select {version=2&q=*:*&wt=javabin&sort=n_l1+asc} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 12019 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_tl1+desc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12024 T544 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626456&version=2&rows=10&wt=javabin&sort=n_tl1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 12024 T535 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626456&version=2&rows=10&wt=javabin&sort=n_tl1+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 12028 T544 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626456&ids=1,133,123,124,135&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12028 T535 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626456&ids=136,147,116,140,130&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12030 T546 C35 P56706 REQ /select {version=2&q=*:*&wt=javabin&sort=n_tl1+desc} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 12033 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_tl1+asc} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12040 T547 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626471&version=2&rows=10&wt=javabin&sort=n_tl1+asc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 12040 T534 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626471&version=2&rows=10&wt=javabin&sort=n_tl1+asc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 12043 T534 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626471&ids=144,6,128,9&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12043 T547 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626471&ids=14,15,138,118,8,10&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12045 T531 C38 P55260 REQ /select {version=2&q=*:*&wt=javabin&sort=n_tl1+asc} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 12050 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_dt1+desc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12056 T544 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626487&version=2&rows=10&wt=javabin&sort=n_dt1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 12056 T535 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626487&version=2&rows=10&wt=javabin&sort=n_dt1+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 12060 T535 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626487&ids=112,104,137,127&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12060 T544 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626487&ids=102,125,148,138,107,109&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12062 T543 C35 P56706 REQ /select {version=2&q=*:*&wt=javabin&sort=n_dt1+desc} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 12066 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_dt1+asc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12072 T534 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626503&version=2&rows=10&wt=javabin&sort=n_dt1+asc&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 12072 T520 C36 P52960 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626503&version=2&rows=10&wt=javabin&sort=n_dt1+asc&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 12077 T520 C36 P52960 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626503&ids=11,100,135,124,114,16,8&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12080 T534 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626503&ids=143,121,128&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=2 
[junit4:junit4]   2> 12081 T531 C38 P55260 REQ /select {version=2&q=*:*&wt=javabin&sort=n_dt1+asc&collection=collection1} hits=67 status=0 QTime=12 
[junit4:junit4]   2> 12086 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_tdt1+desc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12094 T519 C36 P52960 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626525&version=2&rows=10&wt=javabin&sort=n_tdt1+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 12094 T532 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626525&version=2&rows=10&wt=javabin&sort=n_tdt1+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 12100 T519 C36 P52960 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626525&ids=110,132,100,125,8,108,10&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12103 T532 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626525&ids=144,115,141&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12104 T524 C36 P52960 REQ /select {version=2&q=*:*&wt=javabin&sort=n_tdt1+desc} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 12109 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=n_tdt1+asc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12115 T532 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626547&version=2&rows=10&wt=javabin&sort=n_tdt1+asc&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 12116 T545 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626547&version=2&rows=10&wt=javabin&collection=collection1&sort=n_tdt1+asc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 12120 T545 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626547&ids=135,4,138&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12121 T532 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626547&ids=112,126,17,149,106,117,140&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12123 T524 C36 P52960 REQ /select {version=2&q=*:*&wt=javabin&sort=n_tdt1+asc&collection=collection1} hits=67 status=0 QTime=10 
[junit4:junit4]   2> 12127 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=a_si+desc} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12134 T520 C36 P52960 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626566&version=2&rows=10&wt=javabin&sort=a_si+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 12134 T534 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626566&version=2&rows=10&wt=javabin&sort=a_si+desc&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 12138 T534 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626566&ids=2,3,5,7,9&version=2&wt=javabin&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12140 T520 C36 P52960 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626566&ids=12,1,13,8,10&version=2&wt=javabin&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12143 T533 C38 P55260 REQ /select {version=2&q=*:*&wt=javabin&sort=a_si+desc} hits=67 status=0 QTime=11 
[junit4:junit4]   2> 12153 T504 C37 P36044 REQ /select {version=2&q=*:*&wt=javabin&distrib=false&sort=a_si+asc} hits=67 status=0 QTime=4 
[junit4:junit4]   2> 12163 T544 C35 P56706 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626594&version=2&rows=10&wt=javabin&sort=a_si+asc&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 12166 T535 C38 P55260 REQ /select {start=0&distrib=false&fl=id&fsv=true&q=*:*&isShard=true&NOW=1348348626594&version=2&rows=10&wt=javabin&sort=a_si+asc&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 12172 T535 C38 P55260 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626594&ids=2,3,6,7,9&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:55260/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 12173 T544 C35 P56706 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1348348626594&ids=11,1,12,13,4&version=2&wt=javabin&collection=collection1&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} status=0 QTime=1 
[junit4:junit4]   2> 12175 T543 C35 P56706 REQ /select {version=2&q=*:*&wt=javabin&sort=a_si+asc&collection=collection1} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 12179 T504 C37 P36044 REQ /select {q=*:*&distrib=false&fl=*,score&version=2&wt=javabin&sort=a_si+desc} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12187 T519 C36 P52960 REQ /select {start=0&distrib=false&fl=id,score&fsv=true&q=*:*&isShard=true&NOW=1348348626618&version=2&rows=10&wt=javabin&sort=a_si+desc&shard.url=127.0.0.1:52960/solr/collection1/|127.0.0.1:56706/solr/collection1/} hits=32 status=0 QTime=1 
[junit4:jun

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

, STOPWORDS.TXT, HUNSPELL-TEST.AFF, SOLRCONFIG_PERF.XML, SOLRCONFIG-SIMPLELOCK.XML, FRENCHARTICLES.TXT, SOLRCONFIG-LAZYWRITER.XML, SOLRCONFIG-RESPONSE-LOG-COMPONENT.XML, SCHEMA-REPLICATION2.XML, SCHEMA-SNIPPET-TYPE.XML, SCHEMA-SPATIAL.XML, SOLRCONFIG-XINCLUDE.XML, SCHEMA-SPELLCHECKER.XML, XSLT, SOLRCONFIG-DELPOLICY2.XML, TRIVIAL.UPDATEPROCESSOR0.JS, KEEP-1.TXT, BAD-SCHEMA-CURRENCY-DYNAMIC-MULTIVALUED.XML, SCHEMA-TRIE.XML, SCHEMA-BINARYFIELD.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, SCHEMA-MINIMAL.XML, STOPTYPES-2.TXT, SCHEMA_CODEC.XML, STEMDICT.TXT, ELEVATE.XML, SCHEMA-COLLATE.XML, PROTWORDS.TXT, SCHEMA-XINCLUDE.XML, SCHEMA-SNIPPET-FIELD.XML, SOLRCONFIG-REQHANDLER.INCL, SCHEMA-PHRASESUGGEST.XML, SCHEMA-DFR.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, SOLRCONFIG-MASTER1.XML, SOLRCONFIG-FUNCTIONQUERY.XML, SOLRCONFIG-MASTER3.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, SOLRCONFIG-ALTDIRECTORY.XML, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML]
[junit4:junit4]   2> 4032 T1710 oass.SolrIndexSearcher.<init> Opening Searcher@2e7c0392 main
[junit4:junit4]   2> 4032 T1710 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4033 T1710 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4033 T1710 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4034 T1710 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4087 T1710 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4101 T1710 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4102 T1710 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4102 T1710 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4102 T1710 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4103 T1710 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4103 T1710 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4104 T1710 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4104 T1710 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4104 T1710 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4105 T1710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4110 T1717 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e7c0392 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4112 T1710 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4112 T1710 oasu.AbstractSolrTestCase.setUp ####SETUP_END testFieldStatisticsResultsDateField
[junit4:junit4]   2> ASYNC  NEW_CORE C130 name=collection1 org.apache.solr.core.SolrCore@25cbaec8
[junit4:junit4]   2> 4116 T1710 C130 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4393224b lockFactory=org.apache.lucene.store.NativeFSLockFactory@862b8cf3),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4116 T1710 C130 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4117 T1710 C130 UPDATE [collection1] webapp=null path=null params={} {add=[1]} 0 2
[junit4:junit4]   2> 4122 T1710 C130 UPDATE [collection1] webapp=null path=null params={} {add=[2]} 0 0
[junit4:junit4]   2> 4125 T1710 C130 UPDATE [collection1] webapp=null path=null params={} {add=[3]} 0 0
[junit4:junit4]   2> 4128 T1710 C130 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 4130 T1710 C130 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4393224b lockFactory=org.apache.lucene.store.NativeFSLockFactory@862b8cf3),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4393224b lockFactory=org.apache.lucene.store.NativeFSLockFactory@862b8cf3),segFN=segments_2,generation=2,filenames=[_0_MockSep_0.doc, _0_MockSep_0.skp, _0_TestBloomFilteredLucene40Postings_0.prx, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0.fnm, _0_MockSep_0.pyl, _0_MockSep_0.frq, _0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.frq, segments_2, _0.si, _0_MockSep_0.tib, _0.fdt, _0_MockSep_0.tii, _0_MockSep_0.pos, _0.fdx]
[junit4:junit4]   2> 4130 T1710 C130 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 4131 T1710 C130 oass.SolrIndexSearcher.<init> Opening Searcher@4ae062dc main
[junit4:junit4]   2> 4132 T1710 C130 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4132 T1710 C130 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4133 T1717 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ae062dc main{StandardDirectoryReader(segments_2:3 _0(5.0):C3)}
[junit4:junit4]   2> 4134 T1710 C130 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4:junit4]   2> 4136 T1710 C130 REQ [collection1] webapp=null path=null params={q=*:*&stats.field=active_dt&indent=true&stats=true} hits=3 status=0 QTime=1 
[junit4:junit4]   2> 4138 T1710 oasu.AbstractSolrTestCase.tearDown ####TEARDOWN_START testFieldStatisticsResultsDateField
[junit4:junit4]   2> 4139 T1710 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-1665576769
[junit4:junit4]   2> 4139 T1710 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@25cbaec8
[junit4:junit4]   2> 4145 T1710 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 4145 T1710 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 4146 T1710 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 4147 T1710 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {active_i=PostingsFormat(name=NestedPulsing), other_s=PostingsFormat(name=NestedPulsing), active_dt=PostingsFormat(name=MockSep), foo_ss=PostingsFormat(name=MockSep), stats_i=PostingsFormat(name=MockSep), active_s=Lucene40(minBlockSize=44 maxBlockSize=169), id=PostingsFormat(name=TestBloomFilteredLucene40Postings)}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=ar_BH, timezone=Europe/Malta
[junit4:junit4]   2> NOTE: Linux 3.2.0-30-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=91787216,total=216530944
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestHashPartitioner, PrimUtilsTest, DistributedQueryElevationComponentTest, ZkNodePropsTest, CoreContainerCoreInitFailuresTest, ZkCLITest, TestArbitraryIndexDir, TestPhraseSuggestions, XsltUpdateRequestHandlerTest, QueryParsingTest, TestReplicationHandler, TestRecovery, TestPropInjectDefaults, TestPropInject, SolrInfoMBeanTest, StandardRequestHandlerTest, TestMultiCoreConfBootstrap, SoftAutoCommitTest, HighlighterTest, UUIDFieldTest, SolrCoreTest, TestTrie, TestCodecSupport, RAMDirectoryFactoryTest, URLClassifyProcessorTest, ZkSolrClientTest, TestLRUCache, ConvertedLegacyTest, TestDocSet, IndexSchemaTest, LukeRequestHandlerTest, DocumentAnalysisRequestHandlerTest, SuggesterTest, TestValueSourceCache, BasicDistributedZk2Test, TestOmitPositions, TestReversedWildcardFilterFactory, ReturnFieldsTest, TimeZoneUtilsTest, DisMaxRequestHandlerTest, TestQueryUtils, TestReload, TestUpdate, TestStressLucene, TestSort, PrimitiveFieldTypeTest, TestCSVLoader, TestFoldingMultitermQuery, TestSolrCoreProperties, OverseerTest, SimplePostToolTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestFunctionQuery, StatsComponentTest]
[junit4:junit4] Completed on J1 in 2.42s, 7 tests, 1 error <<< FAILURES!

[...truncated 333 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:32: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:179: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:63: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1141: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:814: There were test failures: 230 suites, 950 tests, 4 errors, 7 ignored (2 assumptions)

Total time: 20 minutes 41 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