lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (64bit/ibm-j9-jdk7) - Build # 1145 - Failure!
Date Sun, 16 Sep 2012 16:39:11 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/1145/
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://localhost:48204/solr returned non ok status:500, message:Server Error

Stack Trace:
org.apache.solr.common.SolrException: Server at http://localhost:48204/solr returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([C06FD5AD44748294:41895BB5332BE2A8]: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://localhost:33242/solr returned non ok status:500, message:Server Error

Stack Trace:
org.apache.solr.common.SolrException: Server at http://localhost:33242/solr returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([C06FD5AD44748294:41895BB5332BE2A8]: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.RecoveryZkTest.testDistribSearch

Error Message:
Server at http://localhost:41293/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select

Stack Trace:
org.apache.solr.common.SolrException: Server at http://localhost:41293/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([C06FD5AD44748294:41895BB5332BE2A8]: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.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:613)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:777)


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

Error Message:
Server at http://localhost:51803/solr returned non ok status:500, message:Server Error

Stack Trace:
org.apache.solr.common.SolrException: Server at http://localhost:51803/solr returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([C06FD5AD44748294:41895BB5332BE2A8]: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.handler.component.DistributedQueryElevationComponentTest.doTest(DistributedQueryElevationComponentTest.java:83)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:613)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:777)




Build Log:
[...truncated 7758 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 2 T664 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1347813108801
[junit4:junit4]   2> 3 T664 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T665 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 5 T665 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 5 T665 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 104 T664 oasc.ZkTestServer.run start zk server on port:56851
[junit4:junit4]   2> 104 T664 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56851 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@ae2ce3bf
[junit4:junit4]   2> 105 T670 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56851
[junit4:junit4]   2> 105 T664 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T670 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:56851, initiating session
[junit4:junit4]   2> 106 T666 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44225
[junit4:junit4]   2> 106 T666 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44225
[junit4:junit4]   2> 106 T668 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 127 T668 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cfebec440000 with negotiated timeout 10000 for client /127.0.0.1:44225
[junit4:junit4]   2> 127 T670 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:56851, sessionid = 0x139cfebec440000, negotiated timeout = 10000
[junit4:junit4]   2> 127 T671 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ae2ce3bf name:ZooKeeperConnection Watcher:127.0.0.1:56851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 128 T664 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 128 T664 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 131 T669 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139cfebec440000
[junit4:junit4]   2> 131 T671 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 131 T666 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44225 which had sessionid 0x139cfebec440000
[junit4:junit4]   2> 131 T664 oaz.ZooKeeper.close Session: 0x139cfebec440000 closed
[junit4:junit4]   2> 132 T664 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56851/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2a9a7d74
[junit4:junit4]   2> 133 T672 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56851
[junit4:junit4]   2> 133 T664 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 133 T672 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:56851, initiating session
[junit4:junit4]   2> 133 T666 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44226
[junit4:junit4]   2> 134 T666 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44226
[junit4:junit4]   2> 134 T668 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cfebec440001 with negotiated timeout 10000 for client /127.0.0.1:44226
[junit4:junit4]   2> 134 T672 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:56851, sessionid = 0x139cfebec440001, negotiated timeout = 10000
[junit4:junit4]   2> 135 T673 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2a9a7d74 name:ZooKeeperConnection Watcher:127.0.0.1:56851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 135 T664 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 136 T664 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 138 T664 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 139 T664 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 140 T664 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 142 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 143 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 146 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 147 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 149 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 149 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 151 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 152 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 153 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 154 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 159 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 160 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 162 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 162 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 164 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 164 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 166 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 166 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 167 T664 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 168 T664 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 170 T669 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139cfebec440001
[junit4:junit4]   2> 171 T673 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 171 T664 oaz.ZooKeeper.close Session: 0x139cfebec440001 closed
[junit4:junit4]   2> 171 T666 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44226 which had sessionid 0x139cfebec440001
[junit4:junit4]   2> 172 T664 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 174 T664 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:34310
[junit4:junit4]   2> 174 T664 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 175 T664 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 175 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 184 T664 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 185 T664 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 185 T664 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 186 T664 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 186 T664 oasc.CoreContainer.<init> New CoreContainer -1386699186
[junit4:junit4]   2> 187 T664 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 187 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 200 T664 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 210 T664 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56851/solr
[junit4:junit4]   2> 211 T664 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56851/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@432a9039
[junit4:junit4]   2> 212 T683 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56851
[junit4:junit4]   2> 212 T664 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 213 T683 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:56851, initiating session
[junit4:junit4]   2> 213 T666 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44228
[junit4:junit4]   2> 213 T666 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44228
[junit4:junit4]   2> 214 T668 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cfebec440002 with negotiated timeout 5000 for client /127.0.0.1:44228
[junit4:junit4]   2> 214 T683 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:56851, sessionid = 0x139cfebec440002, negotiated timeout = 5000
[junit4:junit4]   2> 215 T684 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@432a9039 name:ZooKeeperConnection Watcher:127.0.0.1:56851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 215 T664 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 220 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 227 T664 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 230 T664 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34310_solr
[junit4:junit4]   2> 231 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34310_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34310_solr
[junit4:junit4]   2> 232 T664 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34310_solr
[junit4:junit4]   2> 235 T664 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 236 T664 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 236 T664 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 237 T664 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 237 T664 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 237 T664 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 238 T664 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 238 T664 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 239 T664 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 240 T664 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 245 T664 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 249 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 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> 249 T664 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 251 T664 oasc.Overseer.start Overseer (id=88330279898578946-127.0.0.1:34310_solr-n_0000000000) starting
[junit4:junit4]   2> 251 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 252 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 253 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 253 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 255 T686 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 256 T664 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 260 T664 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 263 T685 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 264 T664 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 265 T664 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 266 T664 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 267 T664 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 269 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 269 T664 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 270 T664 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 298 T664 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 342 T664 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 343 T664 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 349 T664 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 784 T664 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 791 T664 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 793 T664 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 829 T664 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 834 T664 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 849 T664 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 850 T664 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 852 T664 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1347813108801/control/data/
[junit4:junit4]   2> 853 T664 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@56be3cf0
[junit4:junit4]   2> 854 T664 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347813108801/control/data/index/
[junit4:junit4]   2> 855 T664 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347813108801/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 856 T664 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347813108801/control/data/index forceNew:false
[junit4:junit4]   2> 860 T664 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347813108801/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 860 T664 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 861 T664 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 862 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 863 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 863 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 864 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 865 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 865 T664 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 866 T664 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 866 T664 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 867 T664 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 867 T664 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 868 T664 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 868 T664 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 869 T664 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 869 T664 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 870 T664 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 870 T664 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 871 T664 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 871 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 872 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 872 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 873 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 873 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 874 T664 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 874 T664 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 875 T664 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 876 T664 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 877 T664 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 878 T664 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> 880 T664 oass.SolrIndexSearcher.<init> Opening Searcher@807ab054 main
[junit4:junit4]   2> 881 T664 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 881 T664 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 882 T664 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 886 T664 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 911 T687 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@807ab054 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 912 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 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> 914 T664 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 914 T664 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:34310/solr shardId:control_shard
[junit4:junit4]   2> 915 T664 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 919 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 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> 919 T664 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 920 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1271 T685 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1271 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 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> 1277 T684 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1422 T664 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1422 T664 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1423 T664 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:34310/solr/collection1/
[junit4:junit4]   2> 1423 T664 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1424 T664 oasc.SyncStrategy.syncToMe http://127.0.0.1:34310/solr/collection1/ has no replicas
[junit4:junit4]   2> 1424 T664 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34310/solr/collection1/
[junit4:junit4]   2> 1424 T664 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1427 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1779 T685 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1782 T684 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1829 T664 oasc.ZkController.register We are http://127.0.0.1:34310/solr/collection1/ and leader is http://127.0.0.1:34310/solr/collection1/
[junit4:junit4]   2> 1829 T664 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34310/solr
[junit4:junit4]   2> 1830 T664 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1831 T664 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1832 T664 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1832 T664 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1833 T664 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1908 T664 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1910 T664 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:41413
[junit4:junit4]   2> 1910 T664 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1911 T664 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634
[junit4:junit4]   2> 1911 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634/'
[junit4:junit4]   2> 1919 T664 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1920 T664 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1920 T664 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634
[junit4:junit4]   2> 1921 T664 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634/solr.xml
[junit4:junit4]   2> 1921 T664 oasc.CoreContainer.<init> New CoreContainer 1258691568
[junit4:junit4]   2> 1921 T664 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634/'
[junit4:junit4]   2> 1922 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634/'
[junit4:junit4]   2> 1933 T664 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1946 T664 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56851/solr
[junit4:junit4]   2> 1947 T664 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56851/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@5a1d819c
[junit4:junit4]   2> 1948 T697 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56851
[junit4:junit4]   2> 1948 T664 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1949 T697 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:56851, initiating session
[junit4:junit4]   2> 1949 T666 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44231
[junit4:junit4]   2> 1949 T666 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44231
[junit4:junit4]   2> 1950 T668 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cfebec440003 with negotiated timeout 5000 for client /127.0.0.1:44231
[junit4:junit4]   2> 1950 T697 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:56851, sessionid = 0x139cfebec440003, negotiated timeout = 5000
[junit4:junit4]   2> 1951 T698 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a1d819c name:ZooKeeperConnection Watcher:127.0.0.1:56851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1951 T664 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1951 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1952 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1953 T664 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41413_solr
[junit4:junit4]   2> 1953 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:41413_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:41413_solr
[junit4:junit4]   2> 1954 T664 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41413_solr
[junit4:junit4]   2> 1955 T684 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 1955 T664 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1956 T664 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1956 T664 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1957 T664 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1957 T664 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1958 T664 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1958 T664 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1958 T664 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1959 T664 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1959 T664 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1964 T664 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1967 T664 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634/collection1
[junit4:junit4]   2> 1967 T664 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 1968 T664 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1968 T664 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 1969 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634/collection1/'
[junit4:junit4]   2> 1970 T664 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty1-1347813110634/collection1/lib/README' to classloader
[junit4:junit4]   2> 1970 T664 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty1-1347813110634/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1994 T664 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 2029 T664 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2030 T664 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2034 T664 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2284 T685 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2287 T684 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2287 T698 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2318 T664 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2323 T664 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2326 T664 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2333 T664 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2336 T664 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2340 T664 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2341 T664 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2342 T664 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1347813110634/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty1/
[junit4:junit4]   2> 2342 T664 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@56be3cf0
[junit4:junit4]   2> 2343 T664 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty1/index/
[junit4:junit4]   2> 2343 T664 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2344 T664 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty1/index forceNew:false
[junit4:junit4]   2> 2347 T664 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2347 T664 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2348 T664 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2349 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2350 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2350 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2351 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2352 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2352 T664 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2353 T664 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2353 T664 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2354 T664 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2354 T664 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2355 T664 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2355 T664 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2356 T664 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2356 T664 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2357 T664 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2357 T664 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2358 T664 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2359 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2359 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2360 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2360 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2361 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2361 T664 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2362 T664 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2363 T664 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2363 T664 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2365 T664 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2366 T664 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> 2368 T664 oass.SolrIndexSearcher.<init> Opening Searcher@c19ee338 main
[junit4:junit4]   2> 2368 T664 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2369 T664 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2370 T664 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2373 T664 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2391 T699 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c19ee338 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2789 T685 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2802 T698 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2802 T684 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2895 T664 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 2895 T664 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:41413/solr shardId:shard1
[junit4:junit4]   2> 2897 T664 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 2902 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440003 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> 2902 T664 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2903 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2905 T664 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2905 T664 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2906 T664 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:41413/solr/collection1/
[junit4:junit4]   2> 2907 T664 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2909 T664 oasc.SyncStrategy.syncToMe http://127.0.0.1:41413/solr/collection1/ has no replicas
[junit4:junit4]   2> 2909 T664 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:41413/solr/collection1/
[junit4:junit4]   2> 2911 T664 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 2915 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3305 T685 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3311 T698 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3311 T684 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3318 T664 oasc.ZkController.register We are http://127.0.0.1:41413/solr/collection1/ and leader is http://127.0.0.1:41413/solr/collection1/
[junit4:junit4]   2> 3318 T664 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:41413/solr
[junit4:junit4]   2> 3319 T664 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3321 T664 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3322 T664 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3322 T664 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3323 T664 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3481 T664 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3483 T664 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:41293
[junit4:junit4]   2> 3483 T664 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3484 T664 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126
[junit4:junit4]   2> 3484 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126/'
[junit4:junit4]   2> 3497 T664 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3497 T664 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3498 T664 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126
[junit4:junit4]   2> 3499 T664 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126/solr.xml
[junit4:junit4]   2> 3499 T664 oasc.CoreContainer.<init> New CoreContainer -1635197536
[junit4:junit4]   2> 3500 T664 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126/'
[junit4:junit4]   2> 3501 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126/'
[junit4:junit4]   2> 3536 T664 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3543 T664 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:56851/solr
[junit4:junit4]   2> 3544 T664 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56851/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@26f13848
[junit4:junit4]   2> 3545 T709 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56851
[junit4:junit4]   2> 3545 T664 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3546 T709 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:56851, initiating session
[junit4:junit4]   2> 3546 T666 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44236
[junit4:junit4]   2> 3546 T666 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44236
[junit4:junit4]   2> 3550 T668 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cfebec440004 with negotiated timeout 5000 for client /127.0.0.1:44236
[junit4:junit4]   2> 3553 T709 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:56851, sessionid = 0x139cfebec440004, negotiated timeout = 5000
[junit4:junit4]   2> 3553 T710 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@26f13848 name:ZooKeeperConnection Watcher:127.0.0.1:56851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3554 T664 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3555 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3556 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3557 T664 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41293_solr
[junit4:junit4]   2> 3557 T669 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139cfebec440004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:41293_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:41293_solr
[junit4:junit4]   2> 3557 T664 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41293_solr
[junit4:junit4]   2> 3559 T698 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3559 T684 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3559 T664 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3560 T664 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3560 T664 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3560 T664 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3561 T664 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3561 T664 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3562 T664 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3562 T664 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3562 T664 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3563 T664 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3568 T664 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3572 T664 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126/collection1
[junit4:junit4]   2> 3572 T664 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3573 T664 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3573 T664 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3574 T664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126/collection1/'
[junit4:junit4]   2> 3575 T664 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty2-1347813112126/collection1/lib/README' to classloader
[junit4:junit4]   2> 3575 T664 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty2-1347813112126/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3610 T664 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3654 T664 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3662 T664 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3666 T664 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3816 T685 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3819 T698 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3819 T684 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3819 T710 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3962 T664 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3967 T664 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3970 T664 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3978 T664 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3981 T664 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3985 T664 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3986 T664 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3987 T664 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1347813112126/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty2/
[junit4:junit4]   2> 3987 T664 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@56be3cf0
[junit4:junit4]   2> 3988 T664 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty2/index/
[junit4:junit4]   2> 3988 T664 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3989 T664 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty2/index forceNew:false
[junit4:junit4]   2> 3992 T664 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3992 T664 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3993 T664 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3995 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3995 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3996 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3996 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3997 T664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3997 T664 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3998 T664 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3998 T664 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3999 T664 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3999 T664 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4000 T664 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4000 T664 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4001 T664 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4001 T664 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4002 T664 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4002 T664 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4003 T664 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4004 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4004 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4005 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4005 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4006 T664 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4006 T664 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4007 T664 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4008 T664 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4008 T664 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4010 T664 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4011 T664 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> 4012 T664 oass.SolrIndexSearcher.<init> Opening Searcher@29496d58 main
[junit4:junit4]   2> 4013 T664 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4014 T664 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4015 T664 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4018 T664 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4037 T711 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29496d58 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4322 T685 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4325 T698 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4325 T710 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4325 T684 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4542 T664 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4543 T664 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:41293/solr shardId:shard1
[junit4:junit4]   2> 4545 T664 oasc.ZkController.register We are http://127.0.0.1:41293/solr/collection1/ and leader is http://127.0.0.1:41413/solr/collection1/
[junit4:junit4]   2> 4546 T664 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:41293/solr
[junit4:junit4]   2> 4546 T664 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 4547 T664 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C40 name=collection1 org.apache.solr.core.SolrCore@cb715d05 url=http://127.0.0.1:41293/solr/collection1 node=127.0.0.1:41293_solr
[junit4:junit4]   2> 4547 T712 C40 P41293 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 4547 T712 C40 P41293 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 4547 T664 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4548 T712 C40 P41293 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:41413/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 4548 T712 C40 P41293 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:41293/solr START replicas=[http://127.0.0.1:41413/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 4548 T664 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4549 T712 C40 P41293 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 4549 T712 C40 P41293 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 4549 T664 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4549 T712 C40 P41293 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 4550 T712 C40 P41293 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4550 T664 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C41 name=collection1 org.apache.solr.core.SolrCore@cf34f4bc url=http://127.0.0.1:41413/solr/collection1 node=127.0.0.1:41413_solr
[junit4:junit4]   2> 4554 T690 C41 P41413 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 4555 T664 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56851/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7a6050cb
[junit4:junit4]   2> 4556 T714 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56851
[junit4:junit4]   2> 4556 T664 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4556 T714 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:56851, initiating session
[junit4:junit4]   2> 4557 T666 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44240
[junit4:junit4]   2> 4557 T666 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44240
[junit4:junit4]   2> 4558 T668 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cfebec440005 with negotiated timeout 10000 for client /127.0.0.1:44240
[junit4:junit4]   2> 4558 T714 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:56851, sessionid = 0x139cfebec440005, negotiated timeout = 10000
[junit4:junit4]   2> 4558 T715 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a6050cb name:ZooKeeperConnection Watcher:127.0.0.1:56851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4558 T664 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4559 T664 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4560 T664 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4560 T664 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 4563 T664 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C42 name=collection1 org.apache.solr.core.SolrCore@11b824d2 url=http://127.0.0.1:34310/solr/collection1 node=127.0.0.1:34310_solr
[junit4:junit4]   2> 4569 T676 C42 P34310 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347813108801/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4570 T676 C42 P34310 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4572 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10001 (1413284483160866816)]} 0 4
[junit4:junit4]   2> 4572 T677 C42 P34310 /update {version=2&wt=javabin} {add=[1 (1413284483160866817)]} 0 4
[junit4:junit4]   2> 4572 T717 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:56851/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@146dec09
[junit4:junit4]   2> 4573 T718 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:56851
[junit4:junit4]   2> 4573 T717 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4574 T718 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:56851, initiating session
[junit4:junit4]   2> 4574 T666 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44244
[junit4:junit4]   2> 4574 T666 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44244
[junit4:junit4]   2> 4575 T668 oazs.NIOServerCnxn.finishSessionInit Established session 0x139cfebec440006 with negotiated timeout 10000 for client /127.0.0.1:44244
[junit4:junit4]   2> 4575 T718 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:56851, sessionid = 0x139cfebec440006, negotiated timeout = 10000
[junit4:junit4]   2> 4575 T719 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@146dec09 name:ZooKeeperConnection Watcher:127.0.0.1:56851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4575 T717 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4576 T717 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4580 T692 C41 P41413 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4580 T692 C41 P41413 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4582 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10001 (1413284483171352576)]} 0 4
[junit4:junit4]   2> 4582 T693 C41 P41413 /update {version=2&wt=javabin} {add=[1 (1413284483171352577)]} 0 4
[junit4:junit4]   2> 4585 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[1 (-1413284483178692609)]} 0 1
[junit4:junit4]   2> 4585 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10001 (-1413284483178692608)]} 0 1
[junit4:junit4]   2> 4587 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[1 (-1413284483180789760)]} 0 0
[junit4:junit4]   2> 4587 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10001 (-1413284483180789761)]} 0 0
[junit4:junit4]   2> 4589 T677 C42 P34310 /update {version=2&wt=javabin} {add=[2 (1413284483182886912)]} 0 0
[junit4:junit4]   2> 4590 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10002 (1413284483182886913)]} 0 1
[junit4:junit4]   2> 4592 T692 C41 P41413 /update {version=2&wt=javabin} {add=[2 (1413284483184984064)]} 0 1
[junit4:junit4]   2> 4592 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10002 (1413284483186032640)]} 0 0
[junit4:junit4]   2> 4594 T676 C42 P34310 /update {version=2&wt=javabin} {add=[3 (1413284483188129792)]} 0 0
[junit4:junit4]   2> 4595 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10003 (1413284483188129793)]} 0 1
[junit4:junit4]   2> 4597 T693 C41 P41413 /update {version=2&wt=javabin} {add=[3 (1413284483191275520)]} 0 0
[junit4:junit4]   2> 4598 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10003 (1413284483191275521)]} 0 1
[junit4:junit4]   2> 4600 T677 C42 P34310 /update {version=2&wt=javabin} {add=[4 (1413284483193372672)]} 0 1
[junit4:junit4]   2> 4600 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10004 (1413284483194421248)]} 0 0
[junit4:junit4]   2> 4602 T692 C41 P41413 /update {version=2&wt=javabin} {add=[4 (1413284483196518400)]} 0 0
[junit4:junit4]   2> 4603 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10004 (1413284483196518401)]} 0 1
[junit4:junit4]   2> 4605 T676 C42 P34310 /update {version=2&wt=javabin} {add=[5 (1413284483199664128)]} 0 0
[junit4:junit4]   2> 4608 T693 C41 P41413 /update {version=2&wt=javabin} {add=[5 (1413284483201761280)]} 0 1
[junit4:junit4]   2> 4612 T676 C42 P34310 /update {version=2&wt=javabin} {add=[6 (1413284483204907008)]} 0 2
[junit4:junit4]   2> 4612 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10005 (1413284483199664129)]} 0 7
[junit4:junit4]   2> 4616 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10005 (1413284483209101313)]} 0 2
[junit4:junit4]   2> 4619 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10006 (1413284483214344192)]} 0 0
[junit4:junit4]   2> 4622 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10006 (1413284483216441344)]} 0 1
[junit4:junit4]   2> 4623 T693 C41 P41413 /update {version=2&wt=javabin} {add=[6 (1413284483209101312)]} 0 9
[junit4:junit4]   2> 4625 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10007 (1413284483219587072)]} 0 1
[junit4:junit4]   2> 4626 T676 C42 P34310 /update {version=2&wt=javabin} {add=[7 (1413284483220635648)]} 0 1
[junit4:junit4]   2> 4627 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10007 (1413284483222732800)]} 0 1
[junit4:junit4]   2> 4629 T692 C41 P41413 /update {version=2&wt=javabin} {add=[7 (1413284483223781376)]} 0 1
[junit4:junit4]   2> 4630 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10008 (1413284483224829952)]} 0 1
[junit4:junit4]   2> 4631 T677 C42 P34310 /update {version=2&wt=javabin} {add=[8 (1413284483226927104)]} 0 0
[junit4:junit4]   2> 4632 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10008 (1413284483227975680)]} 0 0
[junit4:junit4]   2> 4634 T693 C41 P41413 /update {version=2&wt=javabin} {add=[8 (1413284483229024256)]} 0 1
[junit4:junit4]   2> 4635 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10009 (1413284483231121408)]} 0 1
[junit4:junit4]   2> 4637 T676 C42 P34310 /update {version=2&wt=javabin} {add=[9 (1413284483232169984)]} 0 1
[junit4:junit4]   2> 4638 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10009 (1413284483233218560)]} 0 1
[junit4:junit4]   2> 4639 T692 C41 P41413 /update {version=2&wt=javabin} {add=[9 (1413284483235315712)]} 0 0
[junit4:junit4]   2> 4640 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10010 (1413284483236364288)]} 0 0
[junit4:junit4]   2> 4643 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10010 (1413284483239510016)]} 0 1
[junit4:junit4]   2> 4646 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10011 (1413284483241607168)]} 0 1
[junit4:junit4]   2> 4651 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10 (1413284483238461440)]} 0 10
[junit4:junit4]   2> 4655 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10 (1413284483249995776)]} 0 2
[junit4:junit4]   2> 4655 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10011 (1413284483244752896)]} 0 7
[junit4:junit4]   2> 4658 T677 C42 P34310 /update {version=2&wt=javabin} {add=[11 (1413284483254190080)]} 0 1
[junit4:junit4]   2> 4658 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10007 (-1413284483255238656)]} 0 1
[junit4:junit4]   2> 4660 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10007 (-1413284483257335809)]} 0 0
[junit4:junit4]   2> 4660 T692 C41 P41413 /update {version=2&wt=javabin} {add=[11 (1413284483257335808)]} 0 0
[junit4:junit4]   2> 4663 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[7 (-1413284483259432961)]} 0 1
[junit4:junit4]   2> 4663 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10012 (1413284483259432960)]} 0 1
[junit4:junit4]   2> 4665 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[7 (-1413284483262578688)]} 0 1
[junit4:junit4]   2> 4666 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10012 (1413284483262578689)]} 0 1
[junit4:junit4]   2> 4667 T676 C42 P34310 /update {version=2&wt=javabin} {add=[12 (1413284483264675840)]} 0 0
[junit4:junit4]   2> 4668 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10010 (-1413284483265724416)]} 0 0
[junit4:junit4]   2> 4670 T693 C41 P41413 /update {version=2&wt=javabin} {add=[12 (1413284483266772992)]} 0 1
[junit4:junit4]   2> 4670 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10010 (-1413284483267821568)]} 0 0
[junit4:junit4]   2> 4672 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10 (-1413284483269918720)]} 0 0
[junit4:junit4]   2> 4673 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10013 (1413284483269918721)]} 0 1
[junit4:junit4]   2> 4674 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10 (-1413284483272015872)]} 0 0
[junit4:junit4]   2> 4676 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10013 (1413284483273064448)]} 0 1
[junit4:junit4]   2> 4677 T676 C42 P34310 /update {version=2&wt=javabin} {add=[13 (1413284483274113024)]} 0 1
[junit4:junit4]   2> 4678 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10014 (1413284483276210176)]} 0 0
[junit4:junit4]   2> 4679 T693 C41 P41413 /update {version=2&wt=javabin} {add=[13 (1413284483277258752)]} 0 0
[junit4:junit4]   2> 4681 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10014 (1413284483278307328)]} 0 1
[junit4:junit4]   2> 4682 T677 C42 P34310 /update {version=2&wt=javabin} {add=[14 (1413284483279355904)]} 0 1
[junit4:junit4]   2> 4683 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10015 (1413284483281453056)]} 0 0
[junit4:junit4]   2> 4684 T692 C41 P41413 /update {version=2&wt=javabin} {add=[14 (1413284483282501632)]} 0 0
[junit4:junit4]   2> 4686 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10015 (1413284483283550208)]} 0 1
[junit4:junit4]   2> 4689 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10016 (1413284483286695936)]} 0 1
[junit4:junit4]   2> 4696 T676 C42 P34310 /update {version=2&wt=javabin} {add=[15 (1413284483284598784)]} 0 10
[junit4:junit4]   2> 4698 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10016 (1413284483289841664)]} 0 7
[junit4:junit4]   2> 4699 T692 C41 P41413 /update {version=2&wt=javabin} {add=[15 (1413284483297181696)]} 0 1
[junit4:junit4]   2> 4701 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10013 (-1413284483300327424)]} 0 1
[junit4:junit4]   2> 4702 T677 C42 P34310 /update {version=2&wt=javabin} {add=[16 (1413284483301376000)]} 0 1
[junit4:junit4]   2> 4703 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10013 (-1413284483302424576)]} 0 0
[junit4:junit4]   2> 4705 T693 C41 P41413 /update {version=2&wt=javabin} {add=[16 (1413284483303473152)]} 0 1
[junit4:junit4]   2> 4706 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10017 (1413284483304521728)]} 0 1
[junit4:junit4]   2> 4707 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[13 (-1413284483306618880)]} 0 0
[junit4:junit4]   2> 4709 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10017 (1413284483307667456)]} 0 0
[junit4:junit4]   2> 4710 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[13 (-1413284483309764608)]} 0 0
[junit4:junit4]   2> 4711 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10016 (-1413284483310813184)]} 0 1
[junit4:junit4]   2> 4713 T677 C42 P34310 /update {version=2&wt=javabin} {add=[17 (1413284483311861760)]} 0 1
[junit4:junit4]   2> 4713 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10016 (-1413284483312910336)]} 0 0
[junit4:junit4]   2> 4716 T693 C41 P41413 /update {version=2&wt=javabin} {add=[17 (1413284483315007488)]} 0 1
[junit4:junit4]   2> 4716 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10018 (1413284483315007488)]} 0 1
[junit4:junit4]   2> 4718 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[16 (-1413284483318153216)]} 0 0
[junit4:junit4]   2> 4719 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10018 (1413284483318153216)]} 0 1
[junit4:junit4]   2> 4720 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[16 (-1413284483320250368)]} 0 0
[junit4:junit4]   2> 4721 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10019 (1413284483321298944)]} 0 0
[junit4:junit4]   2> 4722 T677 C42 P34310 /update {version=2&wt=javabin} {add=[18 (1413284483322347520)]} 0 0
[junit4:junit4]   2> 4724 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10019 (1413284483323396096)]} 0 1
[junit4:junit4]   2> 4725 T693 C41 P41413 /update {version=2&wt=javabin} {add=[18 (1413284483324444672)]} 0 1
[junit4:junit4]   2> 4726 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10020 (1413284483326541824)]} 0 0
[junit4:junit4]   2> 4727 T676 C42 P34310 /update {version=2&wt=javabin} {add=[19 (1413284483327590400)]} 0 0
[junit4:junit4]   2> 4729 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10020 (1413284483328638976)]} 0 1
[junit4:junit4]   2> 4730 T692 C41 P41413 /update {version=2&wt=javabin} {add=[19 (1413284483329687552)]} 0 1
[junit4:junit4]   2> 4731 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10017 (-1413284483331784704)]} 0 1
[junit4:junit4]   2> 4733 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10017 (-1413284483333881856)]} 0 0
[junit4:junit4]   2> 4737 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10021 (1413284483335979008)]} 0 2
[junit4:junit4]   2> 4742 T677 C42 P34310 /update {version=2&wt=javabin} {add=[20 (1413284483332833280)]} 0 10
[junit4:junit4]   2> 4746 T693 C41 P41413 /update {version=2&wt=javabin} {add=[20 (1413284483345416192)]} 0 2
[junit4:junit4]   2> 4748 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10021 (1413284483340173312)]} 0 9
[junit4:junit4]   2> 4748 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[17 (-1413284483349610496)]} 0 0
[junit4:junit4]   2> 4751 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[17 (-1413284483352756224)]} 0 1
[junit4:junit4]   2> 4751 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10022 (1413284483351707648)]} 0 1
[junit4:junit4]   2> 4753 T677 C42 P34310 /update {version=2&wt=javabin} {add=[21 (1413284483354853376)]} 0 0
[junit4:junit4]   2> 4754 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10022 (1413284483354853376)]} 0 1
[junit4:junit4]   2> 4756 T693 C41 P41413 /update {version=2&wt=javabin} {add=[21 (1413284483356950528)]} 0 1
[junit4:junit4]   2> 4756 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10023 (1413284483357999104)]} 0 0
[junit4:junit4]   2> 4759 T676 C42 P34310 /update {version=2&wt=javabin} {add=[22 (1413284483360096256)]} 0 1
[junit4:junit4]   2> 4759 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10023 (1413284483360096256)]} 0 1
[junit4:junit4]   2> 4762 T692 C41 P41413 /update {version=2&wt=javabin} {add=[22 (1413284483363241984)]} 0 1
[junit4:junit4]   2> 4763 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10024 (1413284483365339136)]} 0 0
[junit4:junit4]   2> 4765 T677 C42 P34310 /update {version=2&wt=javabin} {add=[23 (1413284483367436288)]} 0 0
[junit4:junit4]   2> 4767 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10024 (1413284483368484864)]} 0 1
[junit4:junit4]   2> 4770 T693 C41 P41413 /update {version=2&wt=javabin} {add=[23 (1413284483372679168)]} 0 1
[junit4:junit4]   2> 4772 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10025 (1413284483373727744)]} 0 1
[junit4:junit4]   2> 4773 T676 C42 P34310 /update {version=2&wt=javabin} {add=[24 (1413284483375824896)]} 0 0
[junit4:junit4]   2> 4775 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10025 (1413284483376873472)]} 0 1
[junit4:junit4]   2> 4776 T692 C41 P41413 /update {version=2&wt=javabin} {add=[24 (1413284483377922048)]} 0 1
[junit4:junit4]   2> 4777 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10018 (-1413284483380019200)]} 0 0
[junit4:junit4]   2> 4779 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10018 (-1413284483382116352)]} 0 0
[junit4:junit4]   2> 4783 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10026 (1413284483385262080)]} 0 1
[junit4:junit4]   2> 4788 T677 C42 P34310 /update {version=2&wt=javabin} {add=[25 (1413284483381067776)]} 0 10
[junit4:junit4]   2> 4792 T693 C41 P41413 /update {version=2&wt=javabin} {add=[25 (1413284483394699264)]} 0 2
[junit4:junit4]   2> 4795 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[18 (-1413284483398893568)]} 0 1
[junit4:junit4]   2> 4796 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10026 (1413284483388407808)]} 0 11
[junit4:junit4]   2> 4797 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[18 (-1413284483400990720)]} 0 0
[junit4:junit4]   2> 4798 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10019 (-1413284483402039296)]} 0 0
[junit4:junit4]   2> 4800 T676 C42 P34310 /update {version=2&wt=javabin} {add=[26 (1413284483403087872)]} 0 1
[junit4:junit4]   2> 4800 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10019 (-1413284483404136448)]} 0 0
[junit4:junit4]   2> 4802 T692 C41 P41413 /update {version=2&wt=javabin} {add=[26 (1413284483406233600)]} 0 0
[junit4:junit4]   2> 4803 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10027 (1413284483406233600)]} 0 1
[junit4:junit4]   2> 4804 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[19 (-1413284483408330752)]} 0 0
[junit4:junit4]   2> 4806 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10027 (1413284483409379328)]} 0 1
[junit4:junit4]   2> 4807 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[19 (-1413284483411476480)]} 0 1
[junit4:junit4]   2> 4809 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10028 (1413284483412525056)]} 0 1
[junit4:junit4]   2> 4809 T676 C42 P34310 /update {version=2&wt=javabin} {add=[27 (1413284483413573632)]} 0 0
[junit4:junit4]   2> 4812 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10028 (1413284483415670784)]} 0 1
[junit4:junit4]   2> 4819 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10029 (1413284483421962240)]} 0 2
[junit4:junit4]   2> 4819 T692 C41 P41413 /update {version=2&wt=javabin} {add=[27 (1413284483421962240)]} 0 2
[junit4:junit4]   2> 4822 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10029 (1413284483427205120)]} 0 1
[junit4:junit4]   2> 4825 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10020 (-1413284483430350848)]} 0 0
[junit4:junit4]   2> 4827 T685 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4826 T676 C42 P34310 /update {version=2&wt=javabin} {add=[28 (1413284483430350849)]} 0 1
[junit4:junit4]   2> 4837 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10020 (-1413284483442933760)]} 0 1
[junit4:junit4]   2> 4838 T710 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4838 T715 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4838 T698 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4838 T684 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4840 T719 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4842 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10030 (1413284483447128064)]} 0 1
[junit4:junit4]   2>  C40_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:41293/solr, state=recovering, node_name=127.0.0.1:41293_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 4861 T702 C40 P41293 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347813108801/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4861 T702 C40 P41293 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4864 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 4865 T693 C41 P41413 /update {version=2&wt=javabin} {add=[28 (1413284483450273792)]} 0 21
[junit4:junit4]   2> 4869 T677 C42 P34310 /update {version=2&wt=javabin} {add=[29 (1413284483475439616)]} 0 2
[junit4:junit4]   2> 4870 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 4872 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10030 (1413284483451322368)]} 0 27
[junit4:junit4]   2> 4875 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10021 (-1413284483482779648)]} 0 0
[junit4:junit4]   2> 4880 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 4881 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 4882 T693 C41 P41413 /update {version=2&wt=javabin} {add=[29 (1413284483479633920)]} 0 11
[junit4:junit4]   2> 4882 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10021 (-1413284483485925376)]} 0 4
[junit4:junit4]   2> 4885 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[20 (-1413284483493265408)]} 0 0
[junit4:junit4]   2> 4906 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 4908 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[20 (-1413284483511091200)]} 0 6
[junit4:junit4]   2> 4909 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10031 (1413284483495362560)]} 0 23
[junit4:junit4]   2> 4917 T677 C42 P34310 /update {version=2&wt=javabin} {add=[30 (1413284483524722688)]} 0 1
[junit4:junit4]   2> 4924 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 4925 T693 C41 P41413 /update {version=2&wt=javabin} {add=[30 (1413284483529965568)]} 0 6
[junit4:junit4]   2> 4928 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[21 (-1413284483538354176)]} 0 0
[junit4:junit4]   2> 4933 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 4934 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[21 (-1413284483541499904)]} 0 3
[junit4:junit4]   2> 4938 T677 C42 P34310 /update {version=2&wt=javabin} {add=[31 (1413284483547791360)]} 0 1
[junit4:junit4]   2> 4947 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 4948 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 4949 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10031 (1413284483523674112)]} 0 36
[junit4:junit4]   2> 4949 T693 C41 P41413 /update {version=2&wt=javabin} {add=[31 (1413284483551985664)]} 0 8
[junit4:junit4]   2> 4953 T676 C42 P34310 /update {version=2&wt=javabin} {add=[32 (1413284483563520001)]} 0 2
[junit4:junit4]   2> 4953 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10032 (1413284483563520000)]} 0 2
[junit4:junit4]   2> 4960 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 4961 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 4961 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10032 (1413284483567714305)]} 0 6
[junit4:junit4]   2> 4961 T693 C41 P41413 /update {version=2&wt=javabin} {add=[32 (1413284483567714304)]} 0 6
[junit4:junit4]   2> 4964 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[22 (-1413284483576102913)]} 0 0
[junit4:junit4]   2> 4964 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10022 (-1413284483576102912)]} 0 0
[junit4:junit4]   2> 4969 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 4970 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[22 (-1413284483579248640)]} 0 3
[junit4:junit4]   2> 4971 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 4972 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10022 (-1413284483579248641)]} 0 5
[junit4:junit4]   2> 4974 T677 C42 P34310 /update {version=2&wt=javabin} {add=[33 (1413284483585540096)]} 0 1
[junit4:junit4]   2> 4975 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10033 (1413284483586588672)]} 0 1
[junit4:junit4]   2> 4980 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 4986 T692 C41 P41413 /update {version=2&wt=javabin} {add=[33 (1413284483589734400)]} 0 10
[junit4:junit4]   2> 4991 T676 C42 P34310 /update {version=2&wt=javabin} {add=[34 (1413284483603365888)]} 0 2
[junit4:junit4]   2> 4991 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=11 
[junit4:junit4]   2> 4993 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10033 (1413284483589734401)]} 0 16
[junit4:junit4]   2> 5000 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10034 (1413284483612803072)]} 0 2
[junit4:junit4]   2> 5000 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 5001 T692 C41 P41413 /update {version=2&wt=javabin} {add=[34 (1413284483608608768)]} 0 6
[junit4:junit4]   2> 5005 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[23 (-1413284483619094528)]} 0 0
[junit4:junit4]   2> 5009 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 5010 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10034 (1413284483616997376)]} 0 7
[junit4:junit4]   2> 5010 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5011 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[23 (-1413284483622240256)]} 0 3
[junit4:junit4]   2> 5013 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10023 (-1413284483626434560)]} 0 1
[junit4:junit4]   2> 5015 T677 C42 P34310 /update {version=2&wt=javabin} {add=[35 (1413284483628531712)]} 0 1
[junit4:junit4]   2> 5018 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5019 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10023 (-1413284483630628864)]} 0 3
[junit4:junit4]   2> 5031 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5039 T693 C41 P41413 /update {version=2&wt=javabin} {add=[35 (1413284483631677440)]} 0 22
[junit4:junit4]   2> 5042 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[24 (-1413284483656843264)]} 0 1
[junit4:junit4]   2> 5047 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5058 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[24 (-1413284483661037568)]} 0 14
[junit4:junit4]   2> 5064 T676 C42 P34310 /update {version=2&wt=javabin} {add=[36 (1413284483677814784)]} 0 3
[junit4:junit4]   2> 5087 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10035 (1413284483648454656)]} 0 54
[junit4:junit4]   2> 5095 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5096 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10035 (1413284483708223488)]} 0 6
[junit4:junit4]   2> 5100 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10024 (-1413284483717660672)]} 0 1
[junit4:junit4]   2> 5101 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5104 T693 C41 P41413 /update {version=2&wt=javabin} {add=[36 (1413284483697737728)]} 0 26
[junit4:junit4]   2> 5108 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5115 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10024 (-1413284483722903552)]} 0 11
[junit4:junit4]   2> 5117 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[25 (-1413284483736535040)]} 0 0
[junit4:junit4]   2> 5119 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10036 (1413284483738632192)]} 0 0
[junit4:junit4]   2> 5122 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5123 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[25 (-1413284483739680768)]} 0 3
[junit4:junit4]   2> 5126 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 5128 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10036 (1413284483741777920)]} 0 6
[junit4:junit4]   2> 5128 T676 C42 P34310 /update {version=2&wt=javabin} {add=[37 (1413284483747020800)]} 0 1
[junit4:junit4]   2> 5131 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10025 (-1413284483751215104)]} 0 0
[junit4:junit4]   2> 5136 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5136 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5137 T693 C41 P41413 /update {version=2&wt=javabin} {add=[37 (1413284483752263680)]} 0 5
[junit4:junit4]   2> 5137 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10025 (-1413284483754360832)]} 0 3
[junit4:junit4]   2> 5141 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[26 (-1413284483761700864)]} 0 1
[junit4:junit4]   2> 5144 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10037 (1413284483763798016)]} 0 1
[junit4:junit4]   2> 5147 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5148 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[26 (-1413284483765895168)]} 0 3
[junit4:junit4]   2> 5151 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5152 T677 C42 P34310 /update {version=2&wt=javabin} {add=[38 (1413284483772186624)]} 0 1
[junit4:junit4]   2> 5153 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10037 (1413284483766943744)]} 0 7
[junit4:junit4]   2> 5156 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10026 (-1413284483777429504)]} 0 1
[junit4:junit4]   2> 5158 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5159 T692 C41 P41413 /update {version=2&wt=javabin} {add=[38 (1413284483776380928)]} 0 5
[junit4:junit4]   2> 5162 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5163 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[28 (-1413284483784769536)]} 0 1
[junit4:junit4]   2> 5166 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10026 (-1413284483780575232)]} 0 7
[junit4:junit4]   2> 5169 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5170 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[28 (-1413284483788963840)]} 0 4
[junit4:junit4]   2> 5172 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10038 (1413284483791060992)]} 0 3
[junit4:junit4]   2> 5174 T676 C42 P34310 /update {version=2&wt=javabin} {add=[39 (1413284483795255296)]} 0 1
[junit4:junit4]   2> 5187 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=7 
[junit4:junit4]   2> 5191 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=11 
[junit4:junit4]   2> 5193 T693 C41 P41413 /update {version=2&wt=javabin} {add=[39 (1413284483799449601)]} 0 16
[junit4:junit4]   2> 5194 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10038 (1413284483799449600)]} 0 17
[junit4:junit4]   2> 5196 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[31 (-1413284483819372544)]} 0 0
[junit4:junit4]   2> 5200 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10028 (-1413284483823566848)]} 0 0
[junit4:junit4]   2> 5201 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5202 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[31 (-1413284483822518272)]} 0 3
[junit4:junit4]   2> 5216 T677 C42 P34310 /update {version=2&wt=javabin} {add=[40 (1413284483829858304)]} 0 10
[junit4:junit4]   2> 5217 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5218 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10028 (-1413284483828809728)]} 0 13
[junit4:junit4]   2> 5222 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5223 T693 C41 P41413 /update {version=2&wt=javabin} {add=[40 (1413284483843489792)]} 0 4
[junit4:junit4]   2> 5226 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[32 (-1413284483850829824)]} 0 0
[junit4:junit4]   2> 5224 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10039 (1413284483845586944)]} 0 3
[junit4:junit4]   2> 5231 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5233 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[32 (-1413284483853975552)]} 0 4
[junit4:junit4]   2> 5236 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 5237 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10039 (1413284483857121280)]} 0 5
[junit4:junit4]   2> 5240 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10031 (-1413284483864461312)]} 0 1
[junit4:junit4]   2> 5245 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5246 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10031 (-1413284483867607040)]} 0 4
[junit4:junit4]   2> 5251 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10040 (1413284483874947072)]} 0 2
[junit4:junit4]   2> 5414 T677 C42 P34310 /update {version=2&wt=javabin} {add=[41 (1413284483862364160)]} 0 178
[junit4:junit4]   2> 5419 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5420 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10040 (1413284483880189952)]} 0 166
[junit4:junit4]   2> 5423 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5424 T693 C41 P41413 /update {version=2&wt=javabin} {add=[41 (1413284484052156416)]} 0 6
[junit4:junit4]   2> 5425 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10032 (-1413284484059496448)]} 0 0
[junit4:junit4]   2> 5427 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[34 (-1413284484060545024)]} 0 1
[junit4:junit4]   2> 5431 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5431 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5432 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10032 (-1413284484062642176)]} 0 4
[junit4:junit4]   2> 5433 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[34 (-1413284484063690752)]} 0 4
[junit4:junit4]   2> 5436 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10041 (1413284484069982208)]} 0 1
[junit4:junit4]   2> 5440 T676 C42 P34310 /update {version=2&wt=javabin} {add=[42 (1413284484074176512)]} 0 1
[junit4:junit4]   2> 5447 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5449 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5450 T693 C41 P41413 /update {version=2&wt=javabin} {add=[42 (1413284484080467968)]} 0 6
[junit4:junit4]   2> 5451 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10041 (1413284484078370816)]} 0 9
[junit4:junit4]   2> 5453 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[35 (-1413284484088856576)]} 0 0
[junit4:junit4]   2> 5460 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10034 (-1413284484096196608)]} 0 1
[junit4:junit4]   2> 5464 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5466 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[35 (-1413284484092002304)]} 0 10
[junit4:junit4]   2> 5469 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5471 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10034 (-1413284484102488064)]} 0 5
[junit4:junit4]   2> 5476 T676 C42 P34310 /update {version=2&wt=javabin} {add=[43 (1413284484109828096)]} 0 3
[junit4:junit4]   2> 5481 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10042 (1413284484116119552)]} 0 3
[junit4:junit4]   2> 5488 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 5489 T692 C41 P41413 /update {version=2&wt=javabin} {add=[43 (1413284484117168128)]} 0 9
[junit4:junit4]   2> 5491 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5492 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10042 (1413284484124508160)]} 0 6
[junit4:junit4]   2> 5495 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10035 (-1413284484132896768)]} 0 1
[junit4:junit4]   2> 5497 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[36 (-1413284484131848192)]} 0 4
[junit4:junit4]   2> 5501 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5502 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10035 (-1413284484137091072)]} 0 3
[junit4:junit4]   2> 5506 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5507 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[36 (-1413284484140236800)]} 0 5
[junit4:junit4]   2> 5509 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10043 (1413284484144431104)]} 0 3
[junit4:junit4]   2> 5511 T676 C42 P34310 /update {version=2&wt=javabin} {add=[44 (1413284484148625408)]} 0 1
[junit4:junit4]   2> 5549 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 5552 T692 C41 P41413 /update {version=2&wt=javabin} {add=[44 (1413284484180082688)]} 0 12
[junit4:junit4]   2> 5557 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[39 (-1413284484197908480)]} 0 0
[junit4:junit4]   2> 5586 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=41 
[junit4:junit4]   2> 5587 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10043 (1413284484182179840)]} 0 45
[junit4:junit4]   2> 5589 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5590 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[39 (-1413284484229365760)]} 0 4
[junit4:junit4]   2> 5591 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10036 (-1413284484233560064)]} 0 0
[junit4:junit4]   2> 5596 T676 C42 P34310 /update {version=2&wt=javabin} {add=[45 (1413284484237754368)]} 0 2
[junit4:junit4]   2> 5597 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5598 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10036 (-1413284484236705792)]} 0 4
[junit4:junit4]   2> 5602 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10044 (1413284484244045824)]} 0 1
[junit4:junit4]   2> 5604 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5604 T693 C41 P41413 /update {version=2&wt=javabin} {add=[45 (1413284484242997248)]} 0 4
[junit4:junit4]   2> 5610 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[43 (-1413284484253483008)]} 0 0
[junit4:junit4]   2> 5611 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5612 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10044 (1413284484249288704)]} 0 7
[junit4:junit4]   2> 5616 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10039 (-1413284484259774464)]} 0 1
[junit4:junit4]   2> 5616 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5617 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[43 (-1413284484257677312)]} 0 3
[junit4:junit4]   2> 5624 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5625 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10039 (-1413284484263968768)]} 0 5
[junit4:junit4]   2> 5634 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10045 (1413284484272357376)]} 0 6
[junit4:junit4]   2> 5667 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5669 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10045 (1413284484281794560)]} 0 32
[junit4:junit4]   2> 5672 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10043 (-1413284484318494720)]} 0 0
[junit4:junit4]   2> 5683 T676 C42 P34310 /update {version=2&wt=javabin} {add=[46 (1413284484263968768)]} 0 63
[junit4:junit4]   2> 5688 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5689 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10043 (-1413284484331077632)]} 0 5
[junit4:junit4]   2> 5690 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5691 T693 C41 P41413 /update {version=2&wt=javabin} {add=[46 (1413284484333174784)]} 0 6
[junit4:junit4]   2> 5695 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10046 (1413284484341563392)]} 0 1
[junit4:junit4]   2> 5696 T677 C42 P34310 /update {version=2&wt=javabin} {add=[47 (1413284484341563393)]} 0 1
[junit4:junit4]   2> 5702 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5704 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5705 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10046 (1413284484346806272)]} 0 6
[junit4:junit4]   2> 5706 T692 C41 P41413 /update {version=2&wt=javabin} {add=[47 (1413284484347854848)]} 0 6
[junit4:junit4]   2> 5708 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10047 (1413284484356243456)]} 0 1
[junit4:junit4]   2> 5710 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[44 (-1413284484358340608)]} 0 0
[junit4:junit4]   2> 5714 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5714 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5715 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[44 (-1413284484360437760)]} 0 3
[junit4:junit4]   2> 5715 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10047 (1413284484359389184)]} 0 4
[junit4:junit4]   2> 5718 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10044 (-1413284484366729217)]} 0 0
[junit4:junit4]   2> 5718 T676 C42 P34310 /update {version=2&wt=javabin} {add=[48 (1413284484366729216)]} 0 0
[junit4:junit4]   2> 5724 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5725 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5726 T693 C41 P41413 /update {version=2&wt=javabin} {add=[48 (1413284484369874945)]} 0 5
[junit4:junit4]   2> 5726 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10044 (-1413284484369874944)]} 0 6
[junit4:junit4]   2> 5730 T676 C42 P34310 /update {version=2&wt=javabin} {add=[49 (1413284484378263552)]} 0 1
[junit4:junit4]   2> 5730 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10048 (1413284484378263553)]} 0 1
[junit4:junit4]   2> 5743 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 5746 T692 C41 P41413 /update {version=2&wt=javabin} {add=[49 (1413284484382457856)]} 0 14
[junit4:junit4]   2> 5749 T677 C42 P34310 /update {version=2&wt=javabin} {add=[50 (1413284484399235072)]} 0 1
[junit4:junit4]   2> 5755 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=20 
[junit4:junit4]   2> 5758 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10048 (1413284484382457857)]} 0 26
[junit4:junit4]   2> 5761 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5763 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10049 (1413284484412866560)]} 0 1
[junit4:junit4]   2> 5766 T692 C41 P41413 /update {version=2&wt=javabin} {add=[50 (1413284484406575104)]} 0 10
[junit4:junit4]   2> 5769 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5770 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10049 (1413284484417060864)]} 0 4
[junit4:junit4]   2> 5775 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10050 (1413284484424400896)]} 0 2
[junit4:junit4]   2> 5777 T677 C42 P34310 /update {version=2&wt=javabin} {add=[51 (1413284484420206592)]} 0 9
[junit4:junit4]   2> 5787 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5789 T692 C41 P41413 /update {version=2&wt=javabin} {add=[51 (1413284484430692352)]} 0 10
[junit4:junit4]   2> 5798 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[46 (-1413284484449566720)]} 0 1
[junit4:junit4]   2> 5800 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5802 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10050 (1413284484428595200)]} 0 25
[junit4:junit4]   2> 5807 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10051 (1413284484457955328)]} 0 2
[junit4:junit4]   2> 5808 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5809 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[46 (-1413284484455858176)]} 0 6
[junit4:junit4]   2> 5812 T677 C42 P34310 /update {version=2&wt=javabin} {add=[52 (1413284484465295360)]} 0 1
[junit4:junit4]   2> 5816 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5817 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10051 (1413284484464246784)]} 0 7
[junit4:junit4]   2> 5818 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5819 T692 C41 P41413 /update {version=2&wt=javabin} {add=[52 (1413284484468441088)]} 0 4
[junit4:junit4]   2> 5820 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10046 (-1413284484473683968)]} 0 0
[junit4:junit4]   2> 5822 T676 C42 P34310 /update {version=2&wt=javabin} {add=[53 (1413284484474732544)]} 0 1
[junit4:junit4]   2> 5828 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5829 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5830 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10046 (-1413284484476829696)]} 0 8
[junit4:junit4]   2> 5830 T693 C41 P41413 /update {version=2&wt=javabin} {add=[53 (1413284484478926848)]} 0 5
[junit4:junit4]   2> 5834 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10052 (1413284484486266880)]} 0 2
[junit4:junit4]   2> 5834 T677 C42 P34310 /update {version=2&wt=javabin} {add=[54 (1413284484487315456)]} 0 1
[junit4:junit4]   2> 5841 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5846 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10052 (1413284484491509760)]} 0 9
[junit4:junit4]   2> 5867 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10053 (1413284484521918464)]} 0 2
[junit4:junit4]   2> 5872 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=30 
[junit4:junit4]   2> 5873 T692 C41 P41413 /update {version=2&wt=javabin} {add=[54 (1413284484493606912)]} 0 35
[junit4:junit4]   2> 5875 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 5876 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10053 (1413284484526112768)]} 0 6
[junit4:junit4]   2> 5877 T676 C42 P34310 /update {version=2&wt=javabin} {add=[55 (1413284484532404224)]} 0 1
[junit4:junit4]   2> 5880 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10054 (1413284484536598528)]} 0 1
[junit4:junit4]   2> 5888 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5889 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5889 T693 C41 P41413 /update {version=2&wt=javabin} {add=[55 (1413284484538695680)]} 0 7
[junit4:junit4]   2> 5890 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10054 (1413284484541841408)]} 0 5
[junit4:junit4]   2> 5899 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[47 (-1413284484556521472)]} 0 0
[junit4:junit4]   2> 5905 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5906 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[47 (-1413284484560715776)]} 0 4
[junit4:junit4]   2> 5911 T677 C42 P34310 /update {version=2&wt=javabin} {add=[56 (1413284484567007232)]} 0 2
[junit4:junit4]   2> 5915 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10055 (1413284484556521473)]} 0 16
[junit4:junit4]   2> 5925 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5927 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10055 (1413284484576444416)]} 0 9
[junit4:junit4]   2> 5930 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5931 T692 C41 P41413 /update {version=2&wt=javabin} {add=[56 (1413284484572250112)]} 0 17
[junit4:junit4]   2> 5932 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10047 (-1413284484591124480)]} 0 1
[junit4:junit4]   2> 5935 T677 C42 P34310 /update {version=2&wt=javabin} {add=[57 (1413284484593221632)]} 0 1
[junit4:junit4]   2> 5938 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5941 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5942 T693 C41 P41413 /update {version=2&wt=javabin} {add=[57 (1413284484597415936)]} 0 5
[junit4:junit4]   2> 5945 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10047 (-1413284484594270208)]} 0 10
[junit4:junit4]   2> 5946 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[49 (-1413284484604755968)]} 0 1
[junit4:junit4]   2> 5952 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5953 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[49 (-1413284484607901696)]} 0 5
[junit4:junit4]   2> 5951 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10056 (1413284484609998848)]} 0 4
[junit4:junit4]   2> 5957 T677 C42 P34310 /update {version=2&wt=javabin} {add=[58 (1413284484616290304)]} 0 1
[junit4:junit4]   2> 5962 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5963 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10056 (1413284484617338880)]} 0 6
[junit4:junit4]   2> 5963 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5967 T693 C41 P41413 /update {version=2&wt=javabin} {add=[58 (1413284484620484608)]} 0 7
[junit4:junit4]   2> 5967 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10057 (1413284484627824640)]} 0 1
[junit4:junit4]   2> 5971 T676 C42 P34310 /update {version=2&wt=javabin} {add=[59 (1413284484630970368)]} 0 1
[junit4:junit4]   2> 5974 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5975 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10057 (1413284484630970368)]} 0 5
[junit4:junit4]   2> 5978 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10049 (-1413284484639358976)]} 0 0
[junit4:junit4]   2> 5986 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5988 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10049 (-1413284484642504704)]} 0 7
[junit4:junit4]   2> 5992 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10058 (1413284484652990464)]} 0 1
[junit4:junit4]   2> 6000 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=22 
[junit4:junit4]   2> 6001 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6001 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10058 (1413284484658233344)]} 0 5
[junit4:junit4]   2> 6001 T692 C41 P41413 /update {version=2&wt=javabin} {add=[59 (1413284484635164672)]} 0 27
[junit4:junit4]   2> 6005 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[50 (-1413284484667670528)]} 0 1
[junit4:junit4]   2> 6009 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10059 (1413284484668719104)]} 0 3
[junit4:junit4]   2> 6011 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6012 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[50 (-1413284484670816256)]} 0 5
[junit4:junit4]   2> 6016 T677 C42 P34310 /update {version=2&wt=javabin} {add=[60 (1413284484678156288)]} 0 2
[junit4:junit4]   2> 6022 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6023 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6023 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10059 (1413284484680253440)]} 0 6
[junit4:junit4]   2> 6024 T693 C41 P41413 /update {version=2&wt=javabin} {add=[60 (1413284484682350592)]} 0 5
[junit4:junit4]   2> 6027 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10050 (-1413284484690739200)]} 0 1
[junit4:junit4]   2> 6032 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6033 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10050 (-1413284484693884928)]} 0 3
[junit4:junit4]   2> 6038 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10060 (1413284484700176384)]} 0 2
[junit4:junit4]   2> 6050 T676 C42 P34310 /update {version=2&wt=javabin} {add=[61 (1413284484690739201)]} 0 24
[junit4:junit4]   2> 6060 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6060 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6061 T692 C41 P41413 /update {version=2&wt=javabin} {add=[61 (1413284484720099328)]} 0 6
[junit4:junit4]   2> 6063 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10060 (1413284484706467840)]} 0 21
[junit4:junit4]   2> 6064 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[52 (-1413284484729536512)]} 0 0
[junit4:junit4]   2> 6067 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10061 (1413284484731633664)]} 0 2
[junit4:junit4]   2> 6070 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6071 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[52 (-1413284484733730816)]} 0 3
[junit4:junit4]   2> 6074 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6075 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10061 (1413284484735827968)]} 0 5
[junit4:junit4]   2> 6077 T677 C42 P34310 /update {version=2&wt=javabin} {add=[62 (1413284484742119424)]} 0 1
[junit4:junit4]   2> 6078 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10052 (-1413284484744216576)]} 0 1
[junit4:junit4]   2> 6083 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6084 T692 C41 P41413 /update {version=2&wt=javabin} {add=[62 (1413284484745265152)]} 0 5
[junit4:junit4]   2> 6084 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6085 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10052 (-1413284484746313728)]} 0 5
[junit4:junit4]   2> 6086 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[53 (-1413284484752605184)]} 0 0
[junit4:junit4]   2> 6090 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10062 (1413284484753653760)]} 0 3
[junit4:junit4]   2> 6091 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6092 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[53 (-1413284484754702336)]} 0 4
[junit4:junit4]   2> 6096 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6096 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10062 (1413284484758896640)]} 0 4
[junit4:junit4]   2> 6098 T677 C42 P34310 /update {version=2&wt=javabin} {add=[63 (1413284484764139520)]} 0 1
[junit4:junit4]   2> 6099 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10053 (-1413284484766236672)]} 0 0
[junit4:junit4]   2> 6103 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6104 T692 C41 P41413 /update {version=2&wt=javabin} {add=[63 (1413284484767285248)]} 0 4
[junit4:junit4]   2> 6104 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6106 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10053 (-1413284484769382400)]} 0 4
[junit4:junit4]   2> 6108 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[56 (-1413284484775673856)]} 0 0
[junit4:junit4]   2> 6111 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10063 (1413284484776722432)]} 0 2
[junit4:junit4]   2> 6114 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6117 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[56 (-1413284484778819584)]} 0 6
[junit4:junit4]   2> 6130 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 6131 T677 C42 P34310 /update {version=2&wt=javabin} {add=[64 (1413284484799791104)]} 0 1
[junit4:junit4]   2> 6131 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10063 (1413284484783013888)]} 0 16
[junit4:junit4]   2> 6135 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10056 (-1413284484803985408)]} 0 1
[junit4:junit4]   2> 6139 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6141 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 6142 T692 C41 P41413 /update {version=2&wt=javabin} {add=[64 (1413284484803985408)]} 0 8
[junit4:junit4]   2> 6145 T677 C42 P34310 /update {version=2&wt=javabin} {add=[65 (1413284484813422592)]} 0 1
[junit4:junit4]   2> 6146 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10056 (-1413284484806082560)]} 0 9
[junit4:junit4]   2> 6149 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10064 (1413284484817616896)]} 0 1
[junit4:junit4]   2> 6151 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6152 T692 C41 P41413 /update {version=2&wt=javabin} {add=[65 (1413284484817616896)]} 0 4
[junit4:junit4]   2> 6156 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[58 (-1413284484824956928)]} 0 1
[junit4:junit4]   2> 6164 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6164 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6165 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[58 (-1413284484831248384)]} 0 4
[junit4:junit4]   2> 6167 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10064 (1413284484821811200)]} 0 16
[junit4:junit4]   2> 6178 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10065 (1413284484848025600)]} 0 2
[junit4:junit4]   2> 6181 T677 C42 P34310 /update {version=2&wt=javabin} {add=[66 (1413284484838588416)]} 0 13
[junit4:junit4]   2> 6188 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6189 T692 C41 P41413 /update {version=2&wt=javabin} {add=[66 (1413284484855365632)]} 0 5
[junit4:junit4]   2> 6193 T677 C42 P34310 /update {version=2&wt=javabin} {add=[67 (1413284484863754240)]} 0 1
[junit4:junit4]   2> 6196 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6202 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10065 (1413284484853268480)]} 0 21
[junit4:junit4]   2> 6203 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6204 T692 C41 P41413 /update {version=2&wt=javabin} {add=[67 (1413284484867948544)]} 0 9
[junit4:junit4]   2> 6205 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10058 (-1413284484877385728)]} 0 1
[junit4:junit4]   2> 6206 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[61 (-1413284484878434304)]} 0 0
[junit4:junit4]   2> 6209 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6210 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10058 (-1413284484879482880)]} 0 3
[junit4:junit4]   2> 6211 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6213 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10066 (1413284484884725760)]} 0 1
[junit4:junit4]   2> 6214 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[61 (-1413284484881580032)]} 0 5
[junit4:junit4]   2> 6217 T676 C42 P34310 /update {version=2&wt=javabin} {add=[68 (1413284484888920064)]} 0 1
[junit4:junit4]   2> 6223 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6223 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6224 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10066 (1413284484893114368)]} 0 5
[junit4:junit4]   2> 6224 T693 C41 P41413 /update {version=2&wt=javabin} {add=[68 (1413284484892065792)]} 0 5
[junit4:junit4]   2> 6227 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10067 (1413284484900454400)]} 0 1
[junit4:junit4]   2> 6229 T677 C42 P34310 /update {version=2&wt=javabin} {add=[69 (1413284484901502976)]} 0 2
[junit4:junit4]   2> 6233 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6234 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10067 (1413284484903600128)]} 0 4
[junit4:junit4]   2> 6237 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10061 (-1413284484910940160)]} 0 0
[junit4:junit4]   2> 6243 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6244 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10061 (-1413284484914085888)]} 0 4
[junit4:junit4]   2> 6248 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10068 (1413284484921425920)]} 0 1
[junit4:junit4]   2> 6258 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6259 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10068 (1413284484925620224)]} 0 8
[junit4:junit4]   2> 6263 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10069 (1413284484937154560)]} 0 1
[junit4:junit4]   2> 6269 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6270 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10069 (1413284484941348864)]} 0 5
[junit4:junit4]   2> 6273 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10063 (-1413284484948688896)]} 0 0
[junit4:junit4]   2> 6280 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6281 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10063 (-1413284484951834624)]} 0 5
[junit4:junit4]   2> 6284 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10070 (1413284484960223232)]} 0 1
[junit4:junit4]   2> 6290 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6291 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10070 (1413284484963368960)]} 0 4
[junit4:junit4]   2> 6293 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=58 
[junit4:junit4]   2> 6294 T692 C41 P41413 /update {version=2&wt=javabin} {add=[69 (1413284484904648704)]} 0 63
[junit4:junit4]   2> 6296 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[63 (-1413284484972806144)]} 0 0
[junit4:junit4]   2> 6306 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6308 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[63 (-1413284484975951872)]} 0 9
[junit4:junit4]   2> 6309 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10071 (1413284484970708992)]} 0 15
[junit4:junit4]   2> 6312 T676 C42 P34310 /update {version=2&wt=javabin} {add=[70 (1413284484987486208)]} 0 2
[junit4:junit4]   2> 6319 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6323 T693 C41 P41413 /update {version=2&wt=javabin} {add=[70 (1413284484992729088)]} 0 9
[junit4:junit4]   2> 6327 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6327 T676 C42 P34310 /update {version=2&wt=javabin} {add=[71 (1413284485004263424)]} 0 1
[junit4:junit4]   2> 6328 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10071 (1413284484989583360)]} 0 17
[junit4:junit4]   2> 6331 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10072 (1413284485008457728)]} 0 1
[junit4:junit4]   2> 6333 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6334 T693 C41 P41413 /update {version=2&wt=javabin} {add=[71 (1413284485008457728)]} 0 5
[junit4:junit4]   2> 6337 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6337 T676 C42 P34310 /update {version=2&wt=javabin} {add=[72 (1413284485014749184)]} 0 1
[junit4:junit4]   2> 6338 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10072 (1413284485011603456)]} 0 5
[junit4:junit4]   2> 6341 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10073 (1413284485018943488)]} 0 1
[junit4:junit4]   2> 6343 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6344 T693 C41 P41413 /update {version=2&wt=javabin} {add=[72 (1413284485018943488)]} 0 5
[junit4:junit4]   2> 6348 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6348 T676 C42 P34310 /update {version=2&wt=javabin} {add=[73 (1413284485026283520)]} 0 1
[junit4:junit4]   2> 6349 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10073 (1413284485023137792)]} 0 5
[junit4:junit4]   2> 6351 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10065 (-1413284485030477824)]} 0 0
[junit4:junit4]   2> 6357 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6358 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10065 (-1413284485033623552)]} 0 4
[junit4:junit4]   2> 6361 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10074 (1413284485040963584)]} 0 1
[junit4:junit4]   2> 6367 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=14 
[junit4:junit4]   2> 6367 T693 C41 P41413 /update {version=2&wt=javabin} {add=[73 (1413284485030477824)]} 0 16
[junit4:junit4]   2> 6369 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6369 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10074 (1413284485044109312)]} 0 5
[junit4:junit4]   2> 6370 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[65 (-1413284485050400768)]} 0 1
[junit4:junit4]   2> 6372 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10075 (1413284485052497920)]} 0 0
[junit4:junit4]   2> 6374 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6374 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[65 (-1413284485052497920)]} 0 2
[junit4:junit4]   2> 6377 T677 C42 P34310 /update {version=2&wt=javabin} {add=[74 (1413284485057740800)]} 0 0
[junit4:junit4]   2> 6378 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6379 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10075 (1413284485055643648)]} 0 4
[junit4:junit4]   2> 6383 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6383 T692 C41 P41413 /update {version=2&wt=javabin} {add=[74 (1413284485060886528)]} 0 3
[junit4:junit4]   2> 6387 T676 C42 P34310 /update {version=2&wt=javabin} {add=[75 (1413284485067177984)]} 0 2
[junit4:junit4]   2> 6444 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10076 (1413284485061935104)]} 0 63
[junit4:junit4]   2> 6451 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6451 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10076 (1413284485131141120)]} 0 4
[junit4:junit4]   2> 6454 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10066 (-1413284485138481152)]} 0 0
[junit4:junit4]   2> 6459 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6460 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10066 (-1413284485141626880)]} 0 3
[junit4:junit4]   2> 6469 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10077 (1413284485154209792)]} 0 1
[junit4:junit4]   2> 6474 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6475 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6476 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10077 (1413284485157355520)]} 0 4
[junit4:junit4]   2> 6479 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10068 (-1413284485164695552)]} 0 0
[junit4:junit4]   2> 6476 T692 C41 P41413 /update {version=2&wt=javabin} {add=[75 (1413284485071372288)]} 0 86
[junit4:junit4]   2> 6484 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6485 T677 C42 P34310 /update {version=2&wt=javabin} {add=[76 (1413284485169938432)]} 0 1
[junit4:junit4]   2> 6485 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10068 (-1413284485167841280)]} 0 3
[junit4:junit4]   2> 6489 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10078 (1413284485174132736)]} 0 1
[junit4:junit4]   2> 6491 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6492 T692 C41 P41413 /update {version=2&wt=javabin} {add=[76 (1413284485173084160)]} 0 5
[junit4:junit4]   2> 6495 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6496 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10078 (1413284485178327040)]} 0 4
[junit4:junit4]   2> 6497 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[66 (-1413284485183569920)]} 0 1
[junit4:junit4]   2> 6499 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10069 (-1413284485184618496)]} 0 1
[junit4:junit4]   2> 6502 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6503 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[66 (-1413284485186715648)]} 0 3
[junit4:junit4]   2> 6506 T676 C42 P34310 /update {version=2&wt=javabin} {add=[77 (1413284485191958528)]} 0 1
[junit4:junit4]   2> 6508 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6510 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10069 (-1413284485190909952)]} 0 9
[junit4:junit4]   2> 6512 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6513 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10079 (1413284485200347136)]} 0 1
[junit4:junit4]   2> 6514 T693 C41 P41413 /update {version=2&wt=javabin} {add=[77 (1413284485196152832)]} 0 5
[junit4:junit4]   2> 6519 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[68 (-1413284485206638592)]} 0 0
[junit4:junit4]   2> 6524 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6526 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[68 (-1413284485209784320)]} 0 4
[junit4:junit4]   2> 6529 T676 C42 P34310 /update {version=2&wt=javabin} {add=[78 (1413284485216075776)]} 0 1
[junit4:junit4]   2> 6531 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=9 
[junit4:junit4]   2> 6531 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10079 (1413284485203492864)]} 0 15
[junit4:junit4]   2> 6534 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10071 (-1413284485222367232)]} 0 0
[junit4:junit4]   2> 6535 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6536 T693 C41 P41413 /update {version=2&wt=javabin} {add=[78 (1413284485220270080)]} 0 4
[junit4:junit4]   2> 6538 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6539 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[69 (-1413284485226561536)]} 0 1
[junit4:junit4]   2> 6539 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10071 (-1413284485225512960)]} 0 3
[junit4:junit4]   2> 6542 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10080 (1413284485230755840)]} 0 0
[junit4:junit4]   2> 6543 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6544 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[69 (-1413284485229707264)]} 0 3
[junit4:junit4]   2> 6547 T676 C42 P34310 /update {version=2&wt=javabin} {add=[79 (1413284485234950144)]} 0 1
[junit4:junit4]   2> 6548 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6549 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10080 (1413284485233901568)]} 0 4
[junit4:junit4]   2> 6552 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6553 T693 C41 P41413 /update {version=2&wt=javabin} {add=[79 (1413284485238095872)]} 0 4
[junit4:junit4]   2> 6556 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[71 (-1413284485245435904)]} 0 0
[junit4:junit4]   2> 6561 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6562 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[71 (-1413284485248581632)]} 0 3
[junit4:junit4]   2> 6563 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10081 (1413284485241241600)]} 0 11
[junit4:junit4]   2> 6566 T677 C42 P34310 /update {version=2&wt=javabin} {add=[80 (1413284485254873088)]} 0 1
[junit4:junit4]   2> 6573 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6574 T692 C41 P41413 /update {version=2&wt=javabin} {add=[80 (1413284485259067392)]} 0 5
[junit4:junit4]   2> 6577 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6577 T677 C42 P34310 /update {version=2&wt=javabin} {add=[81 (1413284485267456000)]} 0 1
[junit4:junit4]   2> 6578 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10081 (1413284485255921664)]} 0 13
[junit4:junit4]   2> 6581 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10082 (1413284485270601728)]} 0 1
[junit4:junit4]   2> 6583 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6584 T692 C41 P41413 /update {version=2&wt=javabin} {add=[81 (1413284485270601728)]} 0 4
[junit4:junit4]   2> 6587 T677 C42 P34310 /update {version=2&wt=javabin} {add=[82 (1413284485276893184)]} 0 1
[junit4:junit4]   2> 6589 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6590 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10082 (1413284485274796032)]} 0 7
[junit4:junit4]   2> 6594 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10073 (-1413284485285281792)]} 0 1
[junit4:junit4]   2> 6594 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6595 T692 C41 P41413 /update {version=2&wt=javabin} {add=[82 (1413284485281087488)]} 0 6
[junit4:junit4]   2> 6597 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[73 (-1413284485288427520)]} 0 0
[junit4:junit4]   2> 6598 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6599 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10073 (-1413284485287378944)]} 0 3
[junit4:junit4]   2> 6601 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10083 (1413284485292621824)]} 0 0
[junit4:junit4]   2> 6602 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6605 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[73 (-1413284485291573248)]} 0 5
[junit4:junit4]   2> 6607 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6608 T677 C42 P34310 /update {version=2&wt=javabin} {add=[83 (1413284485299961856)]} 0 1
[junit4:junit4]   2> 6609 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10083 (1413284485295767552)]} 0 5
[junit4:junit4]   2> 6612 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10084 (1413284485303107584)]} 0 1
[junit4:junit4]   2> 6620 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6621 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10084 (1413284485306253312)]} 0 7
[junit4:junit4]   2> 6622 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=9 
[junit4:junit4]   2> 6622 T692 C41 P41413 /update {version=2&wt=javabin} {add=[83 (1413284485303107584)]} 0 11
[junit4:junit4]   2> 6623 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10075 (-1413284485315690496)]} 0 0
[junit4:junit4]   2> 6625 T676 C42 P34310 /update {version=2&wt=javabin} {add=[84 (1413284485317787648)]} 0 1
[junit4:junit4]   2> 6627 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6628 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10075 (-1413284485317787648)]} 0 3
[junit4:junit4]   2> 6631 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10085 (1413284485323030528)]} 0 1
[junit4:junit4]   2> 6631 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6632 T693 C41 P41413 /update {version=2&wt=javabin} {add=[84 (1413284485319884800)]} 0 5
[junit4:junit4]   2> 6634 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[75 (-1413284485327224832)]} 0 0
[junit4:junit4]   2> 6635 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6636 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10085 (1413284485326176256)]} 0 3
[junit4:junit4]   2> 6638 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6638 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10076 (-1413284485331419136)]} 0 0
[junit4:junit4]   2> 6639 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[75 (-1413284485329321984)]} 0 3
[junit4:junit4]   2> 6642 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6643 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10076 (-1413284485333516288)]} 0 3
[junit4:junit4]   2> 6647 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10086 (1413284485338759168)]} 0 2
[junit4:junit4]   2> 6650 T676 C42 P34310 /update {version=2&wt=javabin} {add=[85 (1413284485334564864)]} 0 9
[junit4:junit4]   2> 6656 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6657 T693 C41 P41413 /update {version=2&wt=javabin} {add=[85 (1413284485346099200)]} 0 5
[junit4:junit4]   2> 6660 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[76 (-1413284485354487808)]} 0 1
[junit4:junit4]   2> 6663 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6663 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10086 (1413284485344002048)]} 0 13
[junit4:junit4]   2> 6664 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6665 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[76 (-1413284485356584960)]} 0 3
[junit4:junit4]   2> 6666 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10087 (1413284485360779264)]} 0 1
[junit4:junit4]   2> 6667 T677 C42 P34310 /update {version=2&wt=javabin} {add=[86 (1413284485361827840)]} 0 0
[junit4:junit4]   2> 6672 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6672 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10087 (1413284485363924992)]} 0 4
[junit4:junit4]   2> 6673 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6673 T692 C41 P41413 /update {version=2&wt=javabin} {add=[86 (1413284485364973568)]} 0 3
[junit4:junit4]   2> 6675 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10088 (1413284485370216448)]} 0 0
[junit4:junit4]   2> 6676 T676 C42 P34310 /update {version=2&wt=javabin} {add=[87 (1413284485371265024)]} 0 0
[junit4:junit4]   2> 6680 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6682 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6682 T693 C41 P41413 /update {version=2&wt=javabin} {add=[87 (1413284485374410752)]} 0 3
[junit4:junit4]   2> 6682 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10088 (1413284485373362176)]} 0 5
[junit4:junit4]   2> 6685 T676 C42 P34310 /update {version=2&wt=javabin} {add=[88 (1413284485380702208)]} 0 0
[junit4:junit4]   2> 6686 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10089 (1413284485380702209)]} 0 1
[junit4:junit4]   2> 6697 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6698 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10089 (1413284485383847936)]} 0 10
[junit4:junit4]   2> 6701 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10079 (-1413284485397479424)]} 0 1
[junit4:junit4]   2> 6705 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6706 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[10079 (-1413284485399576576)]} 0 3
[junit4:junit4]   2> 6707 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 6707 T692 C41 P41413 /update {version=2&wt=javabin} {add=[88 (1413284485383847937)]} 0 19
[junit4:junit4]   2> 6709 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10090 (1413284485405868032)]} 0 1
[junit4:junit4]   2> 6710 T676 C42 P34310 /update {version=2&wt=javabin} {add=[89 (1413284485406916608)]} 0 1
[junit4:junit4]   2> 6714 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6715 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6716 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10090 (1413284485409013760)]} 0 5
[junit4:junit4]   2> 6716 T693 C41 P41413 /update {version=2&wt=javabin} {add=[89 (1413284485410062336)]} 0 4
[junit4:junit4]   2> 6719 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[79 (-1413284485416353792)]} 0 0
[junit4:junit4]   2> 6723 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6724 T693 C41 P41413 /update {version=2&wt=javabin} {delete=[79 (-1413284485418450944)]} 0 3
[junit4:junit4]   2> 6727 T677 C42 P34310 /update {version=2&wt=javabin} {add=[90 (1413284485423693824)]} 0 1
[junit4:junit4]   2> 6729 T676 C42 P34310 /update {version=2&wt=javabin} {add=[10091 (1413284485415305216)]} 0 11
[junit4:junit4]   2> 6740 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6741 T692 C41 P41413 /update {version=2&wt=javabin} {add=[10091 (1413284485429985280)]} 0 9
[junit4:junit4]   2> 6744 T676 C42 P34310 /update {version=2&wt=javabin} {delete=[10084 (-1413284485442568192)]} 0 0
[junit4:junit4]   2> 6746 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6746 T693 C41 P41413 /update {version=2&wt=javabin} {add=[90 (1413284485427888128)]} 0 16
[junit4:junit4]   2> 6748 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6749 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10084 (-1413284485444665344)]} 0 3
[junit4:junit4]   2> 6749 T676 C42 P34310 /update {version=2&wt=javabin} {add=[91 (1413284485447811072)]} 0 0
[junit4:junit4]   2> 6752 T677 C42 P34310 /update {version=2&wt=javabin} {add=[10092 (1413284485450956800)]} 0 1
[junit4:junit4]   2> 6755 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6756 T692 C41 P41413 /update {version=2&wt=javabin} {add=[91 (1413284485450956800)]} 0 4
[junit4:junit4]   2> 6759 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[84 (-1413284485457248256)]} 0 1
[junit4:junit4]   2> 6760 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6761 T693 C41 P41413 /update {version=2&wt=javabin} {add=[10092 (1413284485454102528)]} 0 6
[junit4:junit4]   2> 6764 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6765 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[84 (-1413284485461442560)]} 0 4
[junit4:junit4]   2> 6766 T677 C42 P34310 /update {version=2&wt=javabin} {delete=[10086 (-1413284485465636864)]} 0 0
[junit4:junit4]   2> 6768 T676 C42 P34310 /update {version=2&wt=javabin} {add=[92 (1413284485466685440)]} 0 1
[junit4:junit4]   2> 6773 T702 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6774 T692 C41 P41413 /update {version=2&wt=javabin} {delete=[10086 (-1413284485469831168)]} 0 5
[junit4:junit4]   2> 6775 T703 C40 P41293 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41413/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6776 T693 C41 P41413 /update {ver

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

it4:junit4]   2> 12415 T1120 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 12416 T1120 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=172,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 12417 T1120 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 12465 T1120 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 12466 T1120 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 12525 T1120 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestDistributedGrouping -Dtests.method=testDistribSearch -Dtests.seed=C06FD5AD44748294 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en_MT -Dtests.timezone=US/Samoa -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   12.5s J0 | TestDistributedGrouping.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://localhost:48204/solr returned non ok status:500, message:Server Error
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([C06FD5AD44748294:41895BB5332BE2A8]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:371)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:394)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:376)
[junit4:junit4]    > 	at org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:146)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:701)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:613)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:777)
[junit4:junit4]   2> 12549 T1120 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene3x, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=en_MT, timezone=US/Samoa
[junit4:junit4]   2> NOTE: Linux 3.2.0-30-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=136645440,total=242286592
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestStressReorder, MBeansHandlerTest, NumericFieldsTest, SuggesterWFSTTest, TestFastLRUCache, RequestHandlersTest, TestFaceting, CacheHeaderTest, TestPerFieldSimilarity, TermsComponentTest, TestSearchPerf, MinimalSchemaTest, TestJmxMonitoredMap, PreAnalyzedFieldTest, QueryElevationComponentTest, FieldAnalysisRequestHandlerTest, PluginInfoTest, CopyFieldTest, BasicDistributedZkTest, TestPHPSerializedResponseWriter, DirectSolrConnectionTest, NoCacheHeaderTest, TestSolrJ, FileBasedSpellCheckerTest, SpatialFilterTest, TestIBSimilarityFactory, TestConfig, TestFiltering, TestQueryTypes, TestStressRecovery, TestDefaultSimilarityFactory, SpellCheckComponentTest, TestJoin, TestCollationField, TestXIncludeConfig, TestFastOutputStream, DateMathParserTest, TestLMDirichletSimilarityFactory, TestQuerySenderNoQuery, CircularListTest, TestNumberUtils, AlternateDirectoryTest, HighlighterConfigTest, ResourceLoaderTest, SampleTest, TestSolrQueryParser, TestBinaryResponseWriter, NotRequiredUniqueKeyTest, TermVectorComponentDistributedTest, LoggingHandlerTest, WordBreakSolrSpellCheckerTest, CurrencyFieldTest, SpellCheckCollatorTest, MoreLikeThisHandlerTest, FastVectorHighlighterTest, TestRealTimeGet, ZkControllerTest, TestDistributedGrouping]
[junit4:junit4] Completed on J0 in 12.57s, 1 test, 1 error <<< FAILURES!

[...truncated 328 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:32: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:146: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/module-build.xml:63: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1133: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:810: There were test failures: 229 suites, 927 tests, 4 errors, 6 ignored (1 assumption)

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



Mime
View raw message