lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.7.0_07) - Build # 2037 - Failure!
Date Sat, 27 Oct 2012 20:55:51 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2037/
Java: 32bit/jdk1.7.0_07 -client -XX:+UseParallelGC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest

Error Message:
4 threads leaked from SUITE scope at org.apache.solr.cloud.RecoveryZkTest:     1) Thread[id=5761, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=BLOCKED, group=TGRP-RecoveryZkTest]         at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:70)         at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)    2) Thread[id=5763, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=BLOCKED, group=TGRP-RecoveryZkTest]         at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:70)         at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)    3) Thread[id=1548, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=TIMED_WAITING, group=TGRP-RecoveryZkTest]         at java.lang.Thread.sleep(Native Method)         at org.apache.solr.cloud.ZkController.registerAllCoresAsDown(ZkController.java:275)         at org.apache.solr.cloud.ZkController.access$100(ZkController.java:82)         at org.apache.solr.cloud.ZkController$1.command(ZkController.java:190)         at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:116)         at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:46)         at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:90)         at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)    4) Thread[id=1661, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=TIMED_WAITING, group=TGRP-RecoveryZkTest]         at java.lang.Thread.sleep(Native Method)         at org.apache.solr.cloud.ZkController.registerAllCoresAsDown(ZkController.java:275)         at org.apache.solr.cloud.ZkController.access$100(ZkController.java:82)         at org.apache.solr.cloud.ZkController$1.command(ZkController.java:190)         at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:116)         at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:46)         at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:90)         at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.cloud.RecoveryZkTest: 
   1) Thread[id=5761, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=BLOCKED, group=TGRP-RecoveryZkTest]
        at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:70)
        at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
        at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
   2) Thread[id=5763, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=BLOCKED, group=TGRP-RecoveryZkTest]
        at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:70)
        at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
        at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
   3) Thread[id=1548, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=TIMED_WAITING, group=TGRP-RecoveryZkTest]
        at java.lang.Thread.sleep(Native Method)
        at org.apache.solr.cloud.ZkController.registerAllCoresAsDown(ZkController.java:275)
        at org.apache.solr.cloud.ZkController.access$100(ZkController.java:82)
        at org.apache.solr.cloud.ZkController$1.command(ZkController.java:190)
        at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:116)
        at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:46)
        at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:90)
        at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
        at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
   4) Thread[id=1661, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=TIMED_WAITING, group=TGRP-RecoveryZkTest]
        at java.lang.Thread.sleep(Native Method)
        at org.apache.solr.cloud.ZkController.registerAllCoresAsDown(ZkController.java:275)
        at org.apache.solr.cloud.ZkController.access$100(ZkController.java:82)
        at org.apache.solr.cloud.ZkController$1.command(ZkController.java:190)
        at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:116)
        at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:46)
        at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:90)
        at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
        at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
	at __randomizedtesting.SeedInfo.seed([A65B01B51C5E8401]:0)


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

Error Message:
KeeperErrorCode = Session expired for /live_nodes

Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /live_nodes
	at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
	at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1249)
	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:227)
	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:224)
	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:63)
	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:224)
	at org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:293)
	at org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:163)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:129)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:116)
	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:76)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9128 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 2 T1512 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1351370127998
[junit4:junit4]   2> 3 T1512 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T1513 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 4 T1513 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 4 T1513 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 103 T1512 oasc.ZkTestServer.run start zk server on port:55209
[junit4:junit4]   2> 104 T1512 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55209 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@115887b
[junit4:junit4]   2> 105 T1518 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55209
[junit4:junit4]   2> 105 T1512 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 105 T1518 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55209, initiating session
[junit4:junit4]   2> 105 T1514 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52946
[junit4:junit4]   2> 106 T1514 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52946
[junit4:junit4]   2> 106 T1516 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 428 T1516 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa3efba810000 with negotiated timeout 10000 for client /127.0.0.1:52946
[junit4:junit4]   2> 428 T1518 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55209, sessionid = 0x13aa3efba810000, negotiated timeout = 10000
[junit4:junit4]   2> 429 T1519 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@115887b name:ZooKeeperConnection Watcher:127.0.0.1:55209 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 430 T1512 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 431 T1512 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 433 T1517 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa3efba810000
[junit4:junit4]   2> 434 T1519 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 434 T1512 oaz.ZooKeeper.close Session: 0x13aa3efba810000 closed
[junit4:junit4]   2> 435 T1514 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52946 which had sessionid 0x13aa3efba810000
[junit4:junit4]   2> 435 T1512 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55209/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@13ee95e
[junit4:junit4]   2> 436 T1520 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55209
[junit4:junit4]   2> 436 T1512 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 437 T1520 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55209, initiating session
[junit4:junit4]   2> 437 T1514 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52947
[junit4:junit4]   2> 438 T1514 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52947
[junit4:junit4]   2> 439 T1516 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa3efba810001 with negotiated timeout 10000 for client /127.0.0.1:52947
[junit4:junit4]   2> 439 T1520 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55209, sessionid = 0x13aa3efba810001, negotiated timeout = 10000
[junit4:junit4]   2> 440 T1521 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13ee95e name:ZooKeeperConnection Watcher:127.0.0.1:55209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 441 T1512 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 441 T1512 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 444 T1512 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 458 T1512 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 460 T1512 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 463 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 481 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 517 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 517 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 520 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 520 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 522 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 522 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 523 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 524 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 525 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 525 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 527 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 528 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 529 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 530 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 538 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 538 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 539 T1512 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 540 T1512 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 541 T1517 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa3efba810001
[junit4:junit4]   2> 541 T1521 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 541 T1514 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52947 which had sessionid 0x13aa3efba810001
[junit4:junit4]   2> 541 T1512 oaz.ZooKeeper.close Session: 0x13aa3efba810001 closed
[junit4:junit4]   2> 596 T1512 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 599 T1512 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:48467
[junit4:junit4]   2> 600 T1512 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 600 T1512 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538
[junit4:junit4]   2> 600 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/'
[junit4:junit4]   2> 613 T1512 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 613 T1512 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 614 T1512 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538
[junit4:junit4]   2> 614 T1512 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/solr.xml
[junit4:junit4]   2> 614 T1512 oasc.CoreContainer.<init> New CoreContainer 2729188
[junit4:junit4]   2> 615 T1512 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/'
[junit4:junit4]   2> 615 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/'
[junit4:junit4]   2> 635 T1512 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 648 T1512 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55209/solr
[junit4:junit4]   2> 654 T1512 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55209/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@139a3a3
[junit4:junit4]   2> 655 T1531 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55209
[junit4:junit4]   2> 655 T1512 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 656 T1531 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55209, initiating session
[junit4:junit4]   2> 656 T1514 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52948
[junit4:junit4]   2> 656 T1514 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52948
[junit4:junit4]   2> 667 T1531 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55209, sessionid = 0x13aa3efba810002, negotiated timeout = 5000
[junit4:junit4]   2> 667 T1516 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa3efba810002 with negotiated timeout 5000 for client /127.0.0.1:52948
[junit4:junit4]   2> 668 T1532 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@139a3a3 name:ZooKeeperConnection Watcher:127.0.0.1:55209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 668 T1512 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 669 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 669 T1512 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 670 T1512 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48467_solr
[junit4:junit4]   2> 671 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48467_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48467_solr
[junit4:junit4]   2> 671 T1512 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48467_solr
[junit4:junit4]   2> 673 T1512 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 673 T1512 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 673 T1512 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 674 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 674 T1512 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 674 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 675 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 675 T1512 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 675 T1512 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 676 T1512 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 679 T1512 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 691 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 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> 691 T1512 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 692 T1512 oasc.Overseer.start Overseer (id=88563392708673538-127.0.0.1:48467_solr-n_0000000000) starting
[junit4:junit4]   2> 692 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 693 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 693 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 707 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 708 T1534 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 708 T1512 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 709 T1512 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 710 T1533 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 714 T1512 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/collection1
[junit4:junit4]   2> 715 T1512 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 715 T1512 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 716 T1512 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 717 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/collection1/'
[junit4:junit4]   2> 717 T1512 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/collection1/lib/README' to classloader
[junit4:junit4]   2> 718 T1512 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 750 T1512 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 800 T1512 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 801 T1512 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 812 T1512 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1202 T1512 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1208 T1512 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1211 T1512 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1220 T1512 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1223 T1512 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1226 T1512 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1227 T1512 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1228 T1512 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351370128538/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data/
[junit4:junit4]   2> 1228 T1512 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7eea52
[junit4:junit4]   2> 1229 T1512 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 1229 T1512 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for BaseDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1659198 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@139cd58; maxCacheMB=0.1806640625 maxMergeSizeMB=0.51171875)) assuming 'simple'
[junit4:junit4]   2> 1230 T1512 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data forceNew:false
[junit4:junit4]   2> 1230 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data
[junit4:junit4]   2> 1231 T1512 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data/index/
[junit4:junit4]   2> 1231 T1512 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1232 T1512 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data/index forceNew:false
[junit4:junit4]   2> 1233 T1512 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@299499 lockFactory=org.apache.lucene.store.NativeFSLockFactory@33875b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1234 T1512 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1234 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data/index
[junit4:junit4]   2> 1235 T1512 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1236 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1237 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1237 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1238 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1238 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1239 T1512 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1239 T1512 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1239 T1512 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1240 T1512 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1240 T1512 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1241 T1512 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1241 T1512 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1242 T1512 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1242 T1512 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1242 T1512 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1243 T1512 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1243 T1512 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1244 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1244 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1244 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1245 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1245 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1246 T1512 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1246 T1512 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1247 T1512 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1249 T1512 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1251 T1512 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1251 T1512 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1253 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data
[junit4:junit4]   2> 1253 T1512 oass.SolrIndexSearcher.<init> Opening Searcher@2f3455 main
[junit4:junit4]   2> 1254 T1512 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1254 T1512 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1255 T1512 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1380 T1512 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1456 T1535 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f3455 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1460 T1512 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1460 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 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> 1462 T1512 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1462 T1512 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:48467/solr shardId:control_shard
[junit4:junit4]   2> 1465 T1512 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 1469 T1512 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1472 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 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> 1473 T1512 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1473 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1712 T1533 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1713 T1533 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48467_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48467/solr"}
[junit4:junit4]   2> 1713 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 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> 1720 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1978 T1512 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1979 T1512 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1979 T1512 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48467/solr/collection1/
[junit4:junit4]   2> 1980 T1512 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1981 T1512 oasc.SyncStrategy.syncToMe http://127.0.0.1:48467/solr/collection1/ has no replicas
[junit4:junit4]   2> 1981 T1512 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48467/solr/collection1/
[junit4:junit4]   2> 1982 T1512 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 2018 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810002 type:create cxid:0x89 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2223 T1533 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2321 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2358 T1512 oasc.ZkController.register We are http://127.0.0.1:48467/solr/collection1/ and leader is http://127.0.0.1:48467/solr/collection1/
[junit4:junit4]   2> 2359 T1512 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48467/solr
[junit4:junit4]   2> 2359 T1512 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2360 T1512 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2362 T1512 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2363 T1512 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 2363 T1512 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2363 T1512 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2366 T1512 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 2370 T1512 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55209/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@14c51f5
[junit4:junit4]   2> 2370 T1536 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55209
[junit4:junit4]   2> 2371 T1512 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2371 T1536 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55209, initiating session
[junit4:junit4]   2> 2371 T1514 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52949
[junit4:junit4]   2> 2371 T1514 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52949
[junit4:junit4]   2> 2372 T1516 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa3efba810003 with negotiated timeout 10000 for client /127.0.0.1:52949
[junit4:junit4]   2> 2372 T1536 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55209, sessionid = 0x13aa3efba810003, negotiated timeout = 10000
[junit4:junit4]   2> 2372 T1537 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14c51f5 name:ZooKeeperConnection Watcher:127.0.0.1:55209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2372 T1512 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2373 T1512 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2376 T1512 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 2436 T1512 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2437 T1512 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:53780
[junit4:junit4]   2> 2438 T1512 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2438 T1512 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372
[junit4:junit4]   2> 2439 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/'
[junit4:junit4]   2> 2451 T1512 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2451 T1512 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2452 T1512 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372
[junit4:junit4]   2> 2452 T1512 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/solr.xml
[junit4:junit4]   2> 2452 T1512 oasc.CoreContainer.<init> New CoreContainer 2652166
[junit4:junit4]   2> 2453 T1512 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/'
[junit4:junit4]   2> 2453 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/'
[junit4:junit4]   2> 2466 T1512 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2475 T1512 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55209/solr
[junit4:junit4]   2> 2476 T1512 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55209/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ed8b6b
[junit4:junit4]   2> 2477 T1547 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55209
[junit4:junit4]   2> 2477 T1512 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2478 T1547 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55209, initiating session
[junit4:junit4]   2> 2478 T1514 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52950
[junit4:junit4]   2> 2478 T1514 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52950
[junit4:junit4]   2> 2497 T1516 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa3efba810004 with negotiated timeout 5000 for client /127.0.0.1:52950
[junit4:junit4]   2> 2497 T1547 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55209, sessionid = 0x13aa3efba810004, negotiated timeout = 5000
[junit4:junit4]   2> 2498 T1548 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ed8b6b name:ZooKeeperConnection Watcher:127.0.0.1:55209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2498 T1512 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2499 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2517 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2529 T1512 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53780_solr
[junit4:junit4]   2> 2530 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:53780_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:53780_solr
[junit4:junit4]   2> 2537 T1512 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53780_solr
[junit4:junit4]   2> 2557 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2558 T1537 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2558 T1512 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2559 T1532 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2559 T1512 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2559 T1512 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2560 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2561 T1512 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2562 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2562 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2563 T1512 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2564 T1512 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2564 T1512 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2589 T1512 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2596 T1512 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/collection1
[junit4:junit4]   2> 2597 T1512 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2598 T1512 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2598 T1512 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2600 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/collection1/'
[junit4:junit4]   2> 2601 T1512 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/collection1/lib/README' to classloader
[junit4:junit4]   2> 2602 T1512 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2656 T1512 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2704 T1512 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2705 T1512 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2710 T1512 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2823 T1533 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2823 T1533 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48467_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48467/solr"}
[junit4:junit4]   2> 2825 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2826 T1548 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2825 T1537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3089 T1512 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3094 T1512 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3098 T1512 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3108 T1512 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3112 T1512 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3115 T1512 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3117 T1512 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3117 T1512 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351370130372/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1/
[junit4:junit4]   2> 3118 T1512 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7eea52
[junit4:junit4]   2> 3118 T1512 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 3119 T1512 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@198387d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a49e8a) assuming 'simple'
[junit4:junit4]   2> 3119 T1512 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1 forceNew:false
[junit4:junit4]   2> 3120 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1
[junit4:junit4]   2> 3120 T1512 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1/index/
[junit4:junit4]   2> 3120 T1512 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3121 T1512 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1/index forceNew:false
[junit4:junit4]   2> 3122 T1512 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12728d5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@15eb5cd),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3122 T1512 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 3123 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1/index
[junit4:junit4]   2> 3123 T1512 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3125 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3125 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3125 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3126 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3126 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3127 T1512 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3127 T1512 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3128 T1512 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3128 T1512 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3129 T1512 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3129 T1512 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3130 T1512 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3130 T1512 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3130 T1512 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3131 T1512 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3131 T1512 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3132 T1512 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3132 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3133 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3133 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3134 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3134 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3135 T1512 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3135 T1512 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3136 T1512 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3138 T1512 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3140 T1512 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3141 T1512 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3142 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1
[junit4:junit4]   2> 3193 T1512 oass.SolrIndexSearcher.<init> Opening Searcher@325ca3 main
[junit4:junit4]   2> 3194 T1512 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3195 T1512 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3195 T1512 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3199 T1512 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3246 T1549 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@325ca3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3251 T1512 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3329 T1533 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3329 T1533 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:53780_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:53780/solr"}
[junit4:junit4]   2> 3330 T1533 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 3347 T1537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3347 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3347 T1548 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3753 T1512 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3754 T1512 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:53780/solr shardId:shard1
[junit4:junit4]   2> 3754 T1512 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3761 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810004 type:delete cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3761 T1512 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3762 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810004 type:create cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3763 T1512 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3763 T1512 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3763 T1512 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53780/solr/collection1/
[junit4:junit4]   2> 3764 T1512 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3764 T1512 oasc.SyncStrategy.syncToMe http://127.0.0.1:53780/solr/collection1/ has no replicas
[junit4:junit4]   2> 3764 T1512 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53780/solr/collection1/
[junit4:junit4]   2> 3765 T1512 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3772 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810004 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3851 T1533 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3907 T1537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3907 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3908 T1548 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3928 T1512 oasc.ZkController.register We are http://127.0.0.1:53780/solr/collection1/ and leader is http://127.0.0.1:53780/solr/collection1/
[junit4:junit4]   2> 3929 T1512 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53780/solr
[junit4:junit4]   2> 3930 T1512 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3930 T1512 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3948 T1512 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3948 T1512 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3948 T1512 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3949 T1512 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4007 T1512 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4008 T1512 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:38110
[junit4:junit4]   2> 4009 T1512 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4009 T1512 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948
[junit4:junit4]   2> 4009 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/'
[junit4:junit4]   2> 4021 T1512 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4021 T1512 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4022 T1512 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948
[junit4:junit4]   2> 4022 T1512 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/solr.xml
[junit4:junit4]   2> 4022 T1512 oasc.CoreContainer.<init> New CoreContainer 23401457
[junit4:junit4]   2> 4023 T1512 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/'
[junit4:junit4]   2> 4023 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/'
[junit4:junit4]   2> 4035 T1512 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4044 T1512 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55209/solr
[junit4:junit4]   2> 4045 T1512 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55209/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@df672
[junit4:junit4]   2> 4045 T1559 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55209
[junit4:junit4]   2> 4045 T1512 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4047 T1559 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55209, initiating session
[junit4:junit4]   2> 4047 T1514 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52951
[junit4:junit4]   2> 4047 T1514 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52951
[junit4:junit4]   2> 4068 T1516 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa3efba810005 with negotiated timeout 5000 for client /127.0.0.1:52951
[junit4:junit4]   2> 4068 T1559 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55209, sessionid = 0x13aa3efba810005, negotiated timeout = 5000
[junit4:junit4]   2> 4069 T1560 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@df672 name:ZooKeeperConnection Watcher:127.0.0.1:55209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4069 T1512 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4069 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4087 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4098 T1512 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38110_solr
[junit4:junit4]   2> 4099 T1517 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa3efba810005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:38110_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:38110_solr
[junit4:junit4]   2> 4108 T1512 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38110_solr
[junit4:junit4]   2> 4368 T1537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4368 T1548 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4368 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4370 T1512 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4371 T1532 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4370 T1548 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4370 T1537 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4371 T1512 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4372 T1512 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4373 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4374 T1512 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4375 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4376 T1512 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4377 T1512 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4378 T1512 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4379 T1512 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4389 T1512 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4396 T1512 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/collection1
[junit4:junit4]   2> 4397 T1512 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4398 T1512 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4399 T1512 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4400 T1512 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/collection1/'
[junit4:junit4]   2> 4401 T1512 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/collection1/lib/README' to classloader
[junit4:junit4]   2> 4402 T1512 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4432 T1533 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4432 T1533 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:53780_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:53780/solr"}
[junit4:junit4]   2> 4436 T1512 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4449 T1560 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4449 T1548 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4449 T1537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4449 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4480 T1512 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4481 T1512 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4485 T1512 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4893 T1512 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4902 T1512 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4905 T1512 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4911 T1512 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4914 T1512 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4916 T1512 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4917 T1512 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4918 T1512 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351370131948/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2/
[junit4:junit4]   2> 4918 T1512 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7eea52
[junit4:junit4]   2> 4919 T1512 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 4919 T1512 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d1ea63 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@731e01) assuming 'simple'
[junit4:junit4]   2> 4920 T1512 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2 forceNew:false
[junit4:junit4]   2> 4920 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2
[junit4:junit4]   2> 4920 T1512 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2/index/
[junit4:junit4]   2> 4921 T1512 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4921 T1512 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2/index forceNew:false
[junit4:junit4]   2> 4922 T1512 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1af8911 lockFactory=org.apache.lucene.store.NativeFSLockFactory@a17884),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4923 T1512 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4923 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2/index
[junit4:junit4]   2> 4924 T1512 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4925 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4925 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4926 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4926 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4927 T1512 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4927 T1512 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4927 T1512 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4928 T1512 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4928 T1512 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4929 T1512 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4929 T1512 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4930 T1512 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4930 T1512 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4931 T1512 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4931 T1512 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4931 T1512 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4932 T1512 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4932 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4933 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4933 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4934 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4934 T1512 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4935 T1512 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4935 T1512 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4936 T1512 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4938 T1512 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4940 T1512 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4941 T1512 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 4942 T1512 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2
[junit4:junit4]   2> 4943 T1512 oass.SolrIndexSearcher.<init> Opening Searcher@1b5f065 main
[junit4:junit4]   2> 4943 T1512 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4944 T1512 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4944 T1512 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5381 T1512 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5428 T1561 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b5f065 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5431 T1512 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5452 T1533 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5453 T1533 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:38110_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:38110/solr"}
[junit4:junit4]   2> 5455 T1537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5455 T1560 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5455 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5456 T1548 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5932 T1512 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5933 T1512 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:38110/solr shardId:shard1
[junit4:junit4]   2> 5935 T1512 oasc.ZkController.register We are http://127.0.0.1:38110/solr/collection1/ and leader is http://127.0.0.1:53780/solr/collection1/
[junit4:junit4]   2> 5936 T1512 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:38110/solr
[junit4:junit4]   2> 5936 T1512 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 5937 T1512 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C66 name=collection1 org.apache.solr.core.SolrCore@1d92036 url=http://127.0.0.1:38110/solr/collection1 node=127.0.0.1:38110_solr
[junit4:junit4]   2> 5940 T1562 C66 P38110 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 5940 T1512 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5941 T1562 C66 P38110 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 5941 T1562 C66 P38110 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5941 T1512 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 5941 T1512 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5942 T1512 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5967 T1562 C66 P38110 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:53780/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 5967 T1512 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5967 T1562 C66 P38110 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:38110/solr START replicas=[http://127.0.0.1:53780/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 5967 T1533 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5967 T1562 C66 P38110 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 5968 T1562 C66 P38110 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 5968 T1533 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:38110_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:38110/solr"}
[junit4:junit4]   2> 5968 T1562 C66 P38110 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 5973 T1562 C66 P38110 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5975 T1537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5975 T1548 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5975 T1532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5975 T1560 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> ASYNC  NEW_CORE C67 name=collection1 org.apache.solr.core.SolrCore@1fb746b url=http://127.0.0.1:53780/solr/collection1 node=127.0.0.1:53780_solr
[junit4:junit4]   2> 5978 T1545 C67 P53780 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> ASYNC  NEW_CORE C68 name=collection1 org.apache.solr.core.SolrCore@133277d url=http://127.0.0.1:48467/solr/collection1 node=127.0.0.1:48467_solr
[junit4:junit4]   2> 5986 T1528 C68 P48467 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 5991 T1528 C68 P48467 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/control/data
[junit4:junit4]   2> 5992 T1528 C68 P48467 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@299499 lockFactory=org.apache.lucene.store.NativeFSLockFactory@33875b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5993 T1528 C68 P48467 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5995 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[1 (1417014289614503937)]} 0 12
[junit4:junit4]   2> 5996 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10001 (1417014289614503936)]} 0 14
[junit4:junit4]   2> 5999 T1545 C67 P53780 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty1
[junit4:junit4]   2> 6001 T1545 C67 P53780 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@12728d5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@15eb5cd),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6001 T1545 C67 P53780 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2>  C66_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:38110_solr, base_url=http://127.0.0.1:38110/solr}
[junit4:junit4]   2> 6014 T1556 C66 P38110 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351370127998/jetty2
[junit4:junit4]   2> 6016 T1556 C66 P38110 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1af8911 lockFactory=org.apache.lucene.store.NativeFSLockFactory@a17884),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6016 T1556 C66 P38110 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6019 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 6019 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 6019 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10001 (1417014289623941120)]} 0 22
[junit4:junit4]   2> 6019 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[1 (1417014289623941121)]} 0 22
[junit4:junit4]   2> 6022 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10002 (1417014289647009793)]} 0 1
[junit4:junit4]   2> 6022 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[2 (1417014289647009792)]} 0 1
[junit4:junit4]   2> 6027 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6027 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6028 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10002 (1417014289650155520)]} 0 4
[junit4:junit4]   2> 6028 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[2 (1417014289650155521)]} 0 4
[junit4:junit4]   2> 6030 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10003 (1417014289656446976)]} 0 0
[junit4:junit4]   2> 6031 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[3 (1417014289657495552)]} 0 1
[junit4:junit4]   2> 6035 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6035 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6035 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10003 (1417014289658544128)]} 0 3
[junit4:junit4]   2> 6035 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[3 (1417014289659592704)]} 0 2
[junit4:junit4]   2> 6037 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10004 (1417014289663787008)]} 0 0
[junit4:junit4]   2> 6038 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[4 (1417014289663787009)]} 0 1
[junit4:junit4]   2> 6042 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6042 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10004 (1417014289665884160)]} 0 3
[junit4:junit4]   2> 6042 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6043 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[4 (1417014289666932736)]} 0 4
[junit4:junit4]   2> 6044 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10005 (1417014289671127040)]} 0 0
[junit4:junit4]   2> 6045 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[5 (1417014289672175616)]} 0 1
[junit4:junit4]   2> 6049 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6049 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10005 (1417014289673224192)]} 0 3
[junit4:junit4]   2> 6051 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6051 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10006 (1417014289678467072)]} 0 1
[junit4:junit4]   2> 6051 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[5 (1417014289674272768)]} 0 4
[junit4:junit4]   2> 6054 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[6 (1417014289680564224)]} 0 1
[junit4:junit4]   2> 6056 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6056 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10006 (1417014289680564224)]} 0 3
[junit4:junit4]   2> 6058 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6058 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[6 (1417014289682661376)]} 0 3
[junit4:junit4]   2> 6058 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10004 (-1417014289685807104)]} 0 0
[junit4:junit4]   2> 6060 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[4 (-1417014289687904256)]} 0 0
[junit4:junit4]   2> 6064 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6064 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6064 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[4 (-1417014289690001408)]} 0 2
[junit4:junit4]   2> 6064 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10004 (-1417014289687904256)]} 0 4
[junit4:junit4]   2> 6068 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10007 (1417014289694195713)]} 0 2
[junit4:junit4]   2> 6073 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[7 (1417014289694195712)]} 0 7
[junit4:junit4]   2> 6077 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 6078 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10007 (1417014289699438592)]} 0 7
[junit4:junit4]   2> 6078 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6078 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[7 (1417014289703632896)]} 0 3
[junit4:junit4]   2> 6079 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10006 (-1417014289707827200)]} 0 0
[junit4:junit4]   2> 6080 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[6 (-1417014289708875776)]} 0 0
[junit4:junit4]   2> 6083 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6083 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[10006 (-1417014289709924352)]} 0 2
[junit4:junit4]   2> 6084 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6085 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[6 (-1417014289710972928)]} 0 3
[junit4:junit4]   2> 6086 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10008 (1417014289714118656)]} 0 1
[junit4:junit4]   2> 6087 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[8 (1417014289715167232)]} 0 1
[junit4:junit4]   2> 6090 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6091 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10008 (1417014289717264384)]} 0 4
[junit4:junit4]   2> 6091 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6092 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[8 (1417014289718312960)]} 0 4
[junit4:junit4]   2> 6093 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10009 (1417014289722507264)]} 0 0
[junit4:junit4]   2> 6094 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[9 (1417014289722507265)]} 0 1
[junit4:junit4]   2> 6098 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6098 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6098 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10009 (1417014289724604416)]} 0 3
[junit4:junit4]   2> 6099 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[9 (1417014289725652992)]} 0 3
[junit4:junit4]   2> 6100 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10007 (-1417014289729847296)]} 0 0
[junit4:junit4]   2> 6100 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[7 (-1417014289729847297)]} 0 0
[junit4:junit4]   2> 6104 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6104 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6104 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[7 (-1417014289731944449)]} 0 2
[junit4:junit4]   2> 6104 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10007 (-1417014289731944448)]} 0 2
[junit4:junit4]   2> 6106 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10 (1417014289736138752)]} 0 0
[junit4:junit4]   2> 6107 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10010 (1417014289736138753)]} 0 0
[junit4:junit4]   2> 6110 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6111 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10 (1417014289738235904)]} 0 3
[junit4:junit4]   2> 6113 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[8 (-1417014289743478784)]} 0 0
[junit4:junit4]   2> 6114 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6114 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10010 (1417014289738235905)]} 0 6
[junit4:junit4]   2> 6116 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6116 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10008 (-1417014289746624512)]} 0 0
[junit4:junit4]   2> 6116 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[8 (-1417014289744527360)]} 0 2
[junit4:junit4]   2> 6118 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[11 (1417014289748721664)]} 0 0
[junit4:junit4]   2> 6119 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6120 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10008 (-1417014289748721664)]} 0 2
[junit4:junit4]   2> 6122 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10011 (1417014289752915968)]} 0 0
[junit4:junit4]   2> 6123 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6123 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[11 (1417014289750818816)]} 0 3
[junit4:junit4]   2> 6125 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[11 (-1417014289756061696)]} 0 0
[junit4:junit4]   2> 6127 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6127 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10011 (1417014289755013120)]} 0 3
[junit4:junit4]   2> 6128 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6128 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[11 (-1417014289758158848)]} 0 1
[junit4:junit4]   2> 6129 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10011 (-1417014289760256000)]} 0 0
[junit4:junit4]   2> 6131 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[12 (1417014289761304576)]} 0 1
[junit4:junit4]   2> 6132 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6133 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10011 (-1417014289762353152)]} 0 2
[junit4:junit4]   2> 6135 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6135 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10012 (1417014289766547456)]} 0 0
[junit4:junit4]   2> 6135 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[12 (1417014289763401728)]} 0 3
[junit4:junit4]   2> 6138 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[13 (1417014289768644608)]} 0 1
[junit4:junit4]   2> 6140 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6140 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10012 (1417014289768644608)]} 0 3
[junit4:junit4]   2> 6142 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6142 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[13 (1417014289771790336)]} 0 2
[junit4:junit4]   2> 6143 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10013 (1417014289774936064)]} 0 0
[junit4:junit4]   2> 6147 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[14 (1417014289775984640)]} 0 3
[junit4:junit4]   2> 6148 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6149 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10013 (1417014289777033216)]} 0 4
[junit4:junit4]   2> 6151 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10014 (1417014289782276096)]} 0 1
[junit4:junit4]   2> 6154 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6155 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[14 (1417014289781227520)]} 0 6
[junit4:junit4]   2> 6156 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6156 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10014 (1417014289785421824)]} 0 3
[junit4:junit4]   2> 6157 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[12 (-1417014289789616128)]} 0 0
[junit4:junit4]   2> 6158 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10012 (-1417014289790664704)]} 0 0
[junit4:junit4]   2> 6160 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6160 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[12 (-1417014289790664704)]} 0 2
[junit4:junit4]   2> 6161 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6163 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[15 (1417014289794859008)]} 0 1
[junit4:junit4]   2> 6165 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10012 (-1417014289791713280)]} 0 6
[junit4:junit4]   2> 6167 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10015 (1417014289800101888)]} 0 1
[junit4:junit4]   2> 6168 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6169 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[15 (1417014289799053312)]} 0 4
[junit4:junit4]   2> 6171 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[16 (1417014289803247616)]} 0 1
[junit4:junit4]   2> 6173 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6174 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10015 (1417014289802199040)]} 0 5
[junit4:junit4]   2> 6176 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6176 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10016 (1417014289808490496)]} 0 1
[junit4:junit4]   2> 6176 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[16 (1417014289806393344)]} 0 3
[junit4:junit4]   2> 6178 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[15 (-1417014289811636224)]} 0 0
[junit4:junit4]   2> 6180 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6181 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10016 (1417014289811636224)]} 0 4
[junit4:junit4]   2> 6181 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6181 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[15 (-1417014289812684800)]} 0 2
[junit4:junit4]   2> 6182 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[10015 (-1417014289815830528)]} 0 0
[junit4:junit4]   2> 6184 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[17 (1417014289816879104)]} 0 1
[junit4:junit4]   2> 6186 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6186 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10015 (-1417014289817927680)]} 0 2
[junit4:junit4]   2> 6188 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6189 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10017 (1417014289822121984)]} 0 1
[junit4:junit4]   2> 6189 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[17 (1417014289820024832)]} 0 3
[junit4:junit4]   2> 6191 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[18 (1417014289825267712)]} 0 0
[junit4:junit4]   2> 6193 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6194 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10017 (1417014289825267712)]} 0 4
[junit4:junit4]   2> 6195 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6196 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[18 (1417014289827364864)]} 0 3
[junit4:junit4]   2> 6199 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[19 (1417014289832607744)]} 0 1
[junit4:junit4]   2> 6199 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10018 (1417014289830510592)]} 0 4
[junit4:junit4]   2> 6203 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6204 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10018 (1417014289835753472)]} 0 3
[junit4:junit4]   2> 6206 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10019 (1417014289839947776)]} 0 1
[junit4:junit4]   2> 6207 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 6207 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[19 (1417014289834704896)]} 0 7
[junit4:junit4]   2> 6210 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[20 (1417014289844142080)]} 0 1
[junit4:junit4]   2> 6210 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6211 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10019 (1417014289843093504)]} 0 3
[junit4:junit4]   2> 6214 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10020 (1417014289848336384)]} 0 1
[junit4:junit4]   2> 6214 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6214 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[20 (1417014289846239232)]} 0 3
[junit4:junit4]   2> 6216 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[17 (-1417014289851482112)]} 0 0
[junit4:junit4]   2> 6220 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6220 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[17 (-1417014289853579264)]} 0 2
[junit4:junit4]   2> 6223 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6224 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10020 (1417014289851482112)]} 0 7
[junit4:junit4]   2> 6225 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10017 (-1417014289860919296)]} 0 0
[junit4:junit4]   2> 6228 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6229 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10017 (-1417014289863016448)]} 0 2
[junit4:junit4]   2> 6229 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[21 (1417014289857773568)]} 0 7
[junit4:junit4]   2> 6231 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10021 (1417014289867210752)]} 0 0
[junit4:junit4]   2> 6238 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6238 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10021 (1417014289869307904)]} 0 5
[junit4:junit4]   2> 6239 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 6239 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[21 (1417014289867210752)]} 0 8
[junit4:junit4]   2> 6240 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10018 (-1417014289876647936)]} 0 0
[junit4:junit4]   2> 6241 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[18 (-1417014289877696512)]} 0 0
[junit4:junit4]   2> 6244 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6244 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6244 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10018 (-1417014289878745088)]} 0 2
[junit4:junit4]   2> 6244 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[18 (-1417014289879793664)]} 0 2
[junit4:junit4]   2> 6247 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10022 (1417014289882939392)]} 0 1
[junit4:junit4]   2> 6250 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[22 (1417014289886085120)]} 0 1
[junit4:junit4]   2> 6251 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6252 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10022 (1417014289886085120)]} 0 3
[junit4:junit4]   2> 6253 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10019 (-1417014289890279424)]} 0 0
[junit4:junit4]   2> 6254 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6254 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[22 (1417014289888182272)]} 0 3
[junit4:junit4]   2> 6256 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[19 (-1417014289893425152)]} 0 0
[junit4:junit4]   2> 6257 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6257 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10019 (-1417014289892376576)]} 0 2
[junit4:junit4]   2> 6259 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6260 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[19 (-1417014289895522304)]} 0 2
[junit4:junit4]   2> 6260 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10023 (1417014289896570880)]} 0 1
[junit4:junit4]   2> 6262 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[23 (1417014289898668032)]} 0 1
[junit4:junit4]   2> 6264 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6265 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10023 (1417014289899716608)]} 0 3
[junit4:junit4]   2> 6266 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6266 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10021 (-1417014289903910912)]} 0 0
[junit4:junit4]   2> 6267 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[23 (1417014289901813760)]} 0 3
[junit4:junit4]   2> 6268 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[21 (-1417014289906008064)]} 0 0
[junit4:junit4]   2> 6270 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6270 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10021 (-1417014289906008064)]} 0 2
[junit4:junit4]   2> 6272 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6272 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[21 (-1417014289908105216)]} 0 2
[junit4:junit4]   2> 6272 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10024 (1417014289910202368)]} 0 0
[junit4:junit4]   2> 6274 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[24 (1417014289912299520)]} 0 0
[junit4:junit4]   2> 6276 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6277 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10024 (1417014289912299520)]} 0 3
[junit4:junit4]   2> 6279 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6279 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10025 (1417014289916493824)]} 0 1
[junit4:junit4]   2> 6279 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[24 (1417014289914396672)]} 0 3
[junit4:junit4]   2> 6282 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[25 (1417014289919639552)]} 0 1
[junit4:junit4]   2> 6283 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6284 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10025 (1417014289919639552)]} 0 3
[junit4:junit4]   2> 6286 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10022 (-1417014289924882432)]} 0 0
[junit4:junit4]   2> 6288 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6288 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[25 (1417014289921736704)]} 0 5
[junit4:junit4]   2> 6289 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6289 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10022 (-1417014289925931008)]} 0 2
[junit4:junit4]   2> 6290 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[22 (-1417014289929076736)]} 0 0
[junit4:junit4]   2> 6292 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10026 (1417014289930125312)]} 0 1
[junit4:junit4]   2> 6293 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6294 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[22 (-1417014289931173888)]} 0 2
[junit4:junit4]   2> 6296 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6296 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[26 (1417014289934319616)]} 0 1
[junit4:junit4]   2> 6296 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10026 (1417014289932222464)]} 0 3
[junit4:junit4]   2> 6299 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10027 (1417014289937465344)]} 0 1
[junit4:junit4]   2> 6301 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6301 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[26 (1417014289937465344)]} 0 4
[junit4:junit4]   2> 6304 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[27 (1417014289942708224)]} 0 1
[junit4:junit4]   2> 6308 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 6308 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6308 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10027 (1417014289940611072)]} 0 7
[junit4:junit4]   2> 6308 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[27 (1417014289944805376)]} 0 3
[junit4:junit4]   2> 6310 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10028 (1417014289950048256)]} 0 0
[junit4:junit4]   2> 6315 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6315 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[28 (1417014289950048257)]} 0 5
[junit4:junit4]   2> 6316 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10028 (1417014289952145408)]} 0 4
[junit4:junit4]   2> 6318 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10029 (1417014289957388288)]} 0 1
[junit4:junit4]   2> 6319 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6320 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[28 (1417014289957388288)]} 0 3
[junit4:junit4]   2> 6322 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[29 (1417014289962631168)]} 0 0
[junit4:junit4]   2> 6323 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6324 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10029 (1417014289960534016)]} 0 4
[junit4:junit4]   2> 6326 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10025 (-1417014289966825472)]} 0 0
[junit4:junit4]   2> 6327 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6328 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[29 (1417014289964728320)]} 0 4
[junit4:junit4]   2> 6329 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6330 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[25 (-1417014289971019776)]} 0 0
[junit4:junit4]   2> 6330 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10025 (-1417014289968922624)]} 0 2
[junit4:junit4]   2> 6333 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10030 (1417014289973116928)]} 0 1
[junit4:junit4]   2> 6333 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6334 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[25 (-1417014289973116928)]} 0 2
[junit4:junit4]   2> 6336 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[30 (1417014289977311232)]} 0 0
[junit4:junit4]   2> 6337 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6338 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10030 (1417014289976262656)]} 0 3
[junit4:junit4]   2> 6340 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10031 (1417014289980456960)]} 0 1
[junit4:junit4]   2> 6342 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6343 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[30 (1417014289979408384)]} 0 5
[junit4:junit4]   2> 6346 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6346 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10031 (1417014289983602688)]} 0 4
[junit4:junit4]   2> 6346 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[31 (1417014289987796992)]} 0 0
[junit4:junit4]   2> 6349 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10032 (1417014289989894144)]} 0 1
[junit4:junit4]   2> 6351 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6351 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[31 (1417014289989894144)]} 0 3
[junit4:junit4]   2> 6353 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6353 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10032 (1417014289993039872)]} 0 3
[junit4:junit4]   2> 6354 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[32 (1417014289995137024)]} 0 1
[junit4:junit4]   2> 6356 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10033 (1417014289997234176)]} 0 1
[junit4:junit4]   2> 6358 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6358 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[32 (1417014289998282752)]} 0 2
[junit4:junit4]   2> 6360 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6360 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[33 (1417014290002477056)]} 0 0
[junit4:junit4]   2> 6361 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10033 (1417014290000379904)]} 0 3
[junit4:junit4]   2> 6362 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10026 (-1417014290004574208)]} 0 0
[junit4:junit4]   2> 6365 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6366 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10026 (-1417014290006671360)]} 0 2
[junit4:junit4]   2> 6366 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 6367 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[33 (1417014290004574208)]} 0 5
[junit4:junit4]   2> 6368 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[26 (-1417014290010865665)]} 0 0
[junit4:junit4]   2> 6371 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10034 (1417014290010865664)]} 0 3
[junit4:junit4]   2> 6372 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6372 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[26 (-1417014290012962816)]} 0 2
[junit4:junit4]   2> 6374 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[34 (1417014290017157120)]} 0 0
[junit4:junit4]   2> 6374 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6375 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10034 (1417014290015059968)]} 0 3
[junit4:junit4]   2> 6377 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10035 (1417014290019254272)]} 0 1
[junit4:junit4]   2> 6378 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6378 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[34 (1417014290019254272)]} 0 2
[junit4:junit4]   2> 6381 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6381 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10035 (1417014290022400000)]} 0 2
[junit4:junit4]   2> 6383 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[35 (1417014290023448576)]} 0 3
[junit4:junit4]   2> 6383 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[10027 (-1417014290026594304)]} 0 0
[junit4:junit4]   2> 6386 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6387 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10027 (-1417014290028691456)]} 0 2
[junit4:junit4]   2> 6389 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6389 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[35 (1417014290027642880)]} 0 5
[junit4:junit4]   2> 6389 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10036 (1417014290032885760)]} 0 1
[junit4:junit4]   2> 6391 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[27 (-1417014290034982912)]} 0 0
[junit4:junit4]   2> 6394 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6395 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6395 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10036 (1417014290034982912)]} 0 4
[junit4:junit4]   2> 6395 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[27 (-1417014290037080064)]} 0 2
[junit4:junit4]   2> 6397 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10037 (1417014290041274368)]} 0 0
[junit4:junit4]   2> 6397 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[36 (1417014290041274369)]} 0 0
[junit4:junit4]   2> 6401 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6401 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6401 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10037 (1417014290043371520)]} 0 2
[junit4:junit4]   2> 6402 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[36 (1417014290043371521)]} 0 3
[junit4:junit4]   2> 6403 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10032 (-1417014290047565824)]} 0 0
[junit4:junit4]   2> 6404 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[37 (1417014290047565825)]} 0 1
[junit4:junit4]   2> 6407 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6407 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10032 (-1417014290049662976)]} 0 2
[junit4:junit4]   2> 6408 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6408 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[37 (1417014290050711552)]} 0 2
[junit4:junit4]   2> 6409 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10038 (1417014290053857280)]} 0 0
[junit4:junit4]   2> 6410 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[32 (-1417014290054905856)]} 0 0
[junit4:junit4]   2> 6413 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6413 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6413 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[32 (-1417014290057003008)]} 0 2
[junit4:junit4]   2> 6414 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10038 (1417014290055954432)]} 0 3
[junit4:junit4]   2> 6415 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10033 (-1417014290060148737)]} 0 0
[junit4:junit4]   2> 6416 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[38 (1417014290060148736)]} 0 1
[junit4:junit4]   2> 6419 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6419 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10033 (-1417014290062245888)]} 0 2
[junit4:junit4]   2> 6420 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6420 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[38 (1417014290062245889)]} 0 3
[junit4:junit4]   2> 6423 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[33 (-1417014290068537344)]} 0 0
[junit4:junit4]   2> 6424 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10039 (1417014290068537345)]} 0 1
[junit4:junit4]   2> 6427 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6427 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[33 (-1417014290070634496)]} 0 2
[junit4:junit4]   2> 6428 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6428 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10039 (1417014290070634497)]} 0 3
[junit4:junit4]   2> 6430 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[39 (1417014290074828800)]} 0 1
[junit4:junit4]   2> 6430 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10040 (1417014290075877376)]} 0 0
[junit4:junit4]   2> 6436 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6436 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10040 (1417014290077974529)]} 0 4
[junit4:junit4]   2> 6439 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10041 (1417014290084265984)]} 0 1
[junit4:junit4]   2> 6443 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 6443 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[39 (1417014290077974528)]} 0 11
[junit4:junit4]   2> 6446 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[40 (1417014290091606016)]} 0 1
[junit4:junit4]   2> 6459 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6459 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10041 (1417014290087411712)]} 0 18
[junit4:junit4]   2> 6473 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10042 (1417014290108383232)]} 0 12
[junit4:junit4]   2> 6512 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6513 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10042 (1417014290124111872)]} 0 38
[junit4:junit4]   2> 6515 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10037 (-1417014290165006336)]} 0 0
[junit4:junit4]   2> 6518 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6519 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=69 
[junit4:junit4]   2> 6519 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10037 (-1417014290167103488)]} 0 2
[junit4:junit4]   2> 6519 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[40 (1417014290094751744)]} 0 71
[junit4:junit4]   2> 6525 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10043 (1417014290171297792)]} 0 4
[junit4:junit4]   2> 6525 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[41 (1417014290171297793)]} 0 4
[junit4:junit4]   2> 6569 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=41 
[junit4:junit4]   2> 6569 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10043 (1417014290176540672)]} 0 43
[junit4:junit4]   2> 6570 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=41 
[junit4:junit4]   2> 6570 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[41 (1417014290177589248)]} 0 44
[junit4:junit4]   2> 6571 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10038 (-1417014290223726592)]} 0 0
[junit4:junit4]   2> 6575 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6575 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10038 (-1417014290225823744)]} 0 2
[junit4:junit4]   2> 6576 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[42 (1417014290224775168)]} 0 4
[junit4:junit4]   2> 6578 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10044 (1417014290230018048)]} 0 1
[junit4:junit4]   2> 6624 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=45 
[junit4:junit4]   2> 6625 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=44 
[junit4:junit4]   2> 6625 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[42 (1417014290230018048)]} 0 48
[junit4:junit4]   2> 6625 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10044 (1417014290232115200)]} 0 46
[junit4:junit4]   2> 6627 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[37 (-1417014290282446848)]} 0 0
[junit4:junit4]   2> 6627 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10039 (-1417014290282446849)]} 0 0
[junit4:junit4]   2> 6630 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6631 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6631 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[37 (-1417014290284544000)]} 0 2
[junit4:junit4]   2> 6631 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10039 (-1417014290284544001)]} 0 2
[junit4:junit4]   2> 6633 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[43 (1417014290288738304)]} 0 0
[junit4:junit4]   2> 6634 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10045 (1417014290288738305)]} 0 1
[junit4:junit4]   2> 6638 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6639 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6639 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[43 (1417014290290835456)]} 0 4
[junit4:junit4]   2> 6639 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10045 (1417014290291884032)]} 0 3
[junit4:junit4]   2> 6641 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[38 (-1417014290297126912)]} 0 0
[junit4:junit4]   2> 6642 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10046 (1417014290297126913)]} 0 1
[junit4:junit4]   2> 6644 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6645 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[38 (-1417014290298175488)]} 0 3
[junit4:junit4]   2> 6647 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[44 (1417014290302369792)]} 0 1
[junit4:junit4]   2> 6647 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6647 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10046 (1417014290300272640)]} 0 3
[junit4:junit4]   2> 6649 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10047 (1417014290305515520)]} 0 0
[junit4:junit4]   2> 6653 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6654 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[44 (1417014290304466944)]} 0 6
[junit4:junit4]   2> 6656 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[39 (-1417014290311806976)]} 0 1
[junit4:junit4]   2> 6659 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6659 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[39 (-1417014290313904128)]} 0 2
[junit4:junit4]   2> 6660 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 6661 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10047 (1417014290307612672)]} 0 10
[junit4:junit4]   2> 6661 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[45 (1417014290318098432)]} 0 0
[junit4:junit4]   2> 6663 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10048 (1417014290320195584)]} 0 0
[junit4:junit4]   2> 6666 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6666 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[45 (1417014290320195584)]} 0 3
[junit4:junit4]   2> 6668 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6668 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[46 (1417014290325438464)]} 0 0
[junit4:junit4]   2> 6668 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10048 (1417014290322292736)]} 0 3
[junit4:junit4]   2> 6670 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10042 (-1417014290327535616)]} 0 0
[junit4:junit4]   2> 6672 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6673 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[46 (1417014290327535616)]} 0 3
[junit4:junit4]   2> 6673 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6674 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10042 (-1417014290329632768)]} 0 2
[junit4:junit4]   2> 6675 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[47 (1417014290331729920)]} 0 1
[junit4:junit4]   2> 6676 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10049 (1417014290333827072)]} 0 0
[junit4:junit4]   2> 6679 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6680 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[47 (1417014290333827072)]} 0 4
[junit4:junit4]   2> 6680 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6680 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10049 (1417014290335924224)]} 0 2
[junit4:junit4]   2> 6682 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[48 (1417014290339069952)]} 0 1
[junit4:junit4]   2> 6684 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10050 (1417014290340118528)]} 0 2
[junit4:junit4]   2> 6685 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6686 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[48 (1417014290341167104)]} 0 3
[junit4:junit4]   2> 6688 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[42 (-1417014290346409984)]} 0 0
[junit4:junit4]   2> 6689 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6690 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10050 (1417014290344312832)]} 0 4
[junit4:junit4]   2> 6691 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6692 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[10044 (-1417014290350604288)]} 0 1
[junit4:junit4]   2> 6692 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[42 (-1417014290347458560)]} 0 3
[junit4:junit4]   2> 6695 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[49 (1417014290352701440)]} 0 1
[junit4:junit4]   2> 6696 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6696 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[10044 (-1417014290352701440)]} 0 2
[junit4:junit4]   2> 6699 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10051 (1417014290356895744)]} 0 1
[junit4:junit4]   2> 6699 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6700 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[49 (1417014290355847168)]} 0 3
[junit4:junit4]   2> 6702 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[50 (1417014290360041472)]} 0 1
[junit4:junit4]   2> 6704 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6705 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10051 (1417014290358992896)]} 0 5
[junit4:junit4]   2> 6707 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6707 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[10047 (-1417014290366332928)]} 0 0
[junit4:junit4]   2> 6707 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[50 (1417014290363187200)]} 0 3
[junit4:junit4]   2> 6709 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[44 (-1417014290368430080)]} 0 0
[junit4:junit4]   2> 6710 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6711 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[10047 (-1417014290368430080)]} 0 2
[junit4:junit4]   2> 6713 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6714 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[44 (-1417014290370527232)]} 0 3
[junit4:junit4]   2> 6714 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10052 (1417014290372624384)]} 0 1
[junit4:junit4]   2> 6716 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[51 (1417014290375770112)]} 0 0
[junit4:junit4]   2> 6719 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6719 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10052 (1417014290375770112)]} 0 3
[junit4:junit4]   2> 6721 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6722 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[51 (1417014290377867264)]} 0 4
[junit4:junit4]   2> 6722 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10053 (1417014290381012992)]} 0 1
[junit4:junit4]   2> 6724 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[47 (-1417014290384158720)]} 0 0
[junit4:junit4]   2> 6728 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 6728 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10053 (1417014290384158720)]} 0 4
[junit4:junit4]   2> 6728 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6729 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[47 (-1417014290386255872)]} 0 3
[junit4:junit4]   2> 6731 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10054 (1417014290390450176)]} 0 1
[junit4:junit4]   2> 6732 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[52 (1417014290391498752)]} 0 1
[junit4:junit4]   2> 6738 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6739 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[52 (1417014290394644480)]} 0 5
[junit4:junit4]   2> 6743 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 6743 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[53 (1417014290401984512)]} 0 2
[junit4:junit4]   2> 6743 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10054 (1417014290393595904)]} 0 10
[junit4:junit4]   2> 6746 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10051 (-1417014290407227392)]} 0 0
[junit4:junit4]   2> 6748 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6748 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[53 (1417014290406178816)]} 0 3
[junit4:junit4]   2> 6749 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6750 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10051 (-1417014290408275968)]} 0 3
[junit4:junit4]   2> 6751 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[54 (1417014290411421696)]} 0 1
[junit4:junit4]   2> 6752 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10055 (1417014290413518848)]} 0 0
[junit4:junit4]   2> 6756 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6756 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[54 (1417014290414567424)]} 0 3
[junit4:junit4]   2> 6757 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6757 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10055 (1417014290415616000)]} 0 3
[junit4:junit4]   2> 6758 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[51 (-1417014290419810304)]} 0 0
[junit4:junit4]   2> 6759 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[10054 (-1417014290420858880)]} 0 0
[junit4:junit4]   2> 6762 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6762 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[51 (-1417014290421907456)]} 0 2
[junit4:junit4]   2> 6762 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6762 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10054 (-1417014290422956032)]} 0 1
[junit4:junit4]   2> 6766 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10056 (1417014290426101761)]} 0 2
[junit4:junit4]   2> 6770 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[55 (1417014290426101760)]} 0 6
[junit4:junit4]   2> 6775 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6776 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10056 (1417014290430296064)]} 0 8
[junit4:junit4]   2> 6776 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6777 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[55 (1417014290434490368)]} 0 5
[junit4:junit4]   2> 6779 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10057 (1417014290440781824)]} 0 1
[junit4:junit4]   2> 6780 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[54 (-1417014290442878976)]} 0 1
[junit4:junit4]   2> 6784 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6784 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[54 (-1417014290444976128)]} 0 2
[junit4:junit4]   2> 6785 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6786 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10057 (1417014290443927552)]} 0 5
[junit4:junit4]   2> 6788 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[56 (1417014290450219008)]} 0 1
[junit4:junit4]   2> 6789 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10058 (1417014290451267584)]} 0 1
[junit4:junit4]   2> 6794 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6794 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[56 (1417014290453364736)]} 0 4
[junit4:junit4]   2> 6795 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6796 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10058 (1417014290455461888)]} 0 5
[junit4:junit4]   2> 6798 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[57 (1417014290460704768)]} 0 2
[junit4:junit4]   2> 6799 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10059 (1417014290461753344)]} 0 1
[junit4:junit4]   2> 6805 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6806 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10059 (1417014290465947648)]} 0 5
[junit4:junit4]   2> 6806 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6807 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[57 (1417014290463850496)]} 0 7
[junit4:junit4]   2> 6809 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10060 (1417014290472239104)]} 0 1
[junit4:junit4]   2> 6811 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[58 (1417014290474336256)]} 0 1
[junit4:junit4]   2> 6817 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6818 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[58 (1417014290477481984)]} 0 5
[junit4:junit4]   2> 6821 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[59 (1417014290484822016)]} 0 1
[junit4:junit4]   2> 6823 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 6824 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10060 (1417014290476433408)]} 0 12
[junit4:junit4]   2> 6826 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10060 (-1417014290491113472)]} 0 0
[junit4:junit4]   2> 6827 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6828 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[59 (1417014290487967744)]} 0 5
[junit4:junit4]   2> 6831 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6831 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[60 (1417014290495307776)]} 0 1
[junit4:junit4]   2> 6832 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10060 (-1417014290494259200)]} 0 3
[junit4:junit4]   2> 6835 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10061 (1417014290499502080)]} 0 1
[junit4:junit4]   2> 6842 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6843 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10061 (1417014290503696384)]} 0 6
[junit4:junit4]   2> 6847 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10062 (1417014290511036416)]} 0 2
[junit4:junit4]   2> 6851 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 6852 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[60 (1417014290499502080)]} 0 18
[junit4:junit4]   2> 6853 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6854 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10062 (1417014290515230720)]} 0 5
[junit4:junit4]   2> 6854 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[60 (-1417014290520473600)]} 0 0
[junit4:junit4]   2> 6856 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10062 (-1417014290522570752)]} 0 0
[junit4:junit4]   2> 6859 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6859 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[60 (-1417014290523619328)]} 0 3
[junit4:junit4]   2> 6860 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6861 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10062 (-1417014290524667904)]} 0 3
[junit4:junit4]   2> 6862 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[61 (1417014290528862208)]} 0 1
[junit4:junit4]   2> 6864 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10063 (1417014290529910784)]} 0 1
[junit4:junit4]   2> 6868 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6869 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[61 (1417014290532007936)]} 0 4
[junit4:junit4]   2> 6870 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6871 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10063 (1417014290534105088)]} 0 4
[junit4:junit4]   2> 6875 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10064 (1417014290540396544)]} 0 2
[junit4:junit4]   2> 6880 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[62 (1417014290538299392)]} 0 9
[junit4:junit4]   2> 6881 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6882 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10064 (1417014290544590848)]} 0 5
[junit4:junit4]   2> 6885 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10065 (1417014290551930880)]} 0 1
[junit4:junit4]   2> 6887 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6888 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[62 (1417014290550882304)]} 0 5
[junit4:junit4]   2> 6891 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[62 (-1417014290559270912)]} 0 0
[junit4:junit4]   2> 6891 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6892 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10065 (1417014290555076608)]} 0 5
[junit4:junit4]   2> 6895 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6896 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[62 (-1417014290561368064)]} 0 3
[junit4:junit4]   2> 6896 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10066 (1417014290563465216)]} 0 2
[junit4:junit4]   2> 6900 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[63 (1417014290566610944)]} 0 2
[junit4:junit4]   2> 6903 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6904 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10066 (1417014290567659520)]} 0 5
[junit4:junit4]   2> 6905 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6906 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[63 (1417014290570805248)]} 0 4
[junit4:junit4]   2> 6908 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10067 (1417014290574999552)]} 0 2
[junit4:junit4]   2> 6909 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[64 (1417014290577096704)]} 0 1
[junit4:junit4]   2> 6916 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6917 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[64 (1417014290581291008)]} 0 5
[junit4:junit4]   2> 6920 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[65 (1417014290588631040)]} 0 1
[junit4:junit4]   2> 6921 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6921 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10067 (1417014290579193856)]} 0 11
[junit4:junit4]   2> 6923 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10063 (-1417014290592825344)]} 0 0
[junit4:junit4]   2> 6925 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6925 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[65 (1417014290591776768)]} 0 3
[junit4:junit4]   2> 6926 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6926 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10063 (-1417014290594922496)]} 0 2
[junit4:junit4]   2> 6927 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[66 (1417014290597019648)]} 0 0
[junit4:junit4]   2> 6929 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10068 (1417014290598068224)]} 0 1
[junit4:junit4]   2> 6934 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6935 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10068 (1417014290600165376)]} 0 5
[junit4:junit4]   2> 6937 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10065 (-1417014290607505408)]} 0 0
[junit4:junit4]   2> 6938 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 6938 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[66 (1417014290599116800)]} 0 9
[junit4:junit4]   2> 6940 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6940 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[67 (1417014290610651136)]} 0 0
[junit4:junit4]   2> 6941 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[10065 (-1417014290609602560)]} 0 2
[junit4:junit4]   2> 6943 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10069 (1417014290613796864)]} 0 0
[junit4:junit4]   2> 6945 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6946 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[67 (1417014290612748288)]} 0 4
[junit4:junit4]   2> 6948 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[63 (-1417014290619039744)]} 0 0
[junit4:junit4]   2> 6948 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6949 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10069 (1417014290615894016)]} 0 4
[junit4:junit4]   2> 6951 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10066 (-1417014290622185472)]} 0 0
[junit4:junit4]   2> 6952 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6952 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[63 (-1417014290621136896)]} 0 2
[junit4:junit4]   2> 6954 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6955 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[68 (1417014290625331200)]} 0 1
[junit4:junit4]   2> 6955 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[10066 (-1417014290624282624)]} 0 2
[junit4:junit4]   2> 6960 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6960 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[68 (1417014290628476928)]} 0 3
[junit4:junit4]   2> 6962 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[65 (-1417014290633719808)]} 0 0
[junit4:junit4]   2> 6963 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10070 (1417014290628476928)]} 0 6
[junit4:junit4]   2> 6965 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6965 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[65 (-1417014290634768384)]} 0 2
[junit4:junit4]   2> 6967 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6967 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10070 (1417014290635816960)]} 0 3
[junit4:junit4]   2> 6968 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[69 (1417014290638962688)]} 0 1
[junit4:junit4]   2> 6974 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6974 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[69 (1417014290642108416)]} 0 4
[junit4:junit4]   2> 6976 T1530 C68 P48467 /update {wt=javabin&version=2} {delete=[66 (-1417014290648399872)]} 0 0
[junit4:junit4]   2> 6977 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10071 (1417014290641059840)]} 0 8
[junit4:junit4]   2> 6980 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6980 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[66 (-1417014290650497024)]} 0 2
[junit4:junit4]   2> 6982 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[70 (1417014290654691328)]} 0 1
[junit4:junit4]   2> 6987 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 6987 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10071 (1417014290651545600)]} 0 8
[junit4:junit4]   2> 6988 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6989 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[70 (1417014290656788480)]} 0 5
[junit4:junit4]   2> 6990 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10067 (-1417014290662031360)]} 0 1
[junit4:junit4]   2> 6992 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[71 (1417014290664128512)]} 0 1
[junit4:junit4]   2> 6994 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6994 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[10067 (-1417014290665177088)]} 0 2
[junit4:junit4]   2> 6998 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10072 (1417014290670419968)]} 0 2
[junit4:junit4]   2> 6998 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6999 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[71 (1417014290668322816)]} 0 5
[junit4:junit4]   2> 7001 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[67 (-1417014290674614272)]} 0 0
[junit4:junit4]   2> 7004 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7004 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10072 (1417014290673565696)]} 0 4
[junit4:junit4]   2> 7005 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7006 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[67 (-1417014290677760000)]} 0 3
[junit4:junit4]   2> 7008 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10073 (1417014290680905728)]} 0 1
[junit4:junit4]   2> 7010 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[72 (1417014290683002880)]} 0 2
[junit4:junit4]   2> 7014 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7015 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10073 (1417014290684051456)]} 0 5
[junit4:junit4]   2> 7016 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7016 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[72 (1417014290686148608)]} 0 4
[junit4:junit4]   2> 7018 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10074 (1417014290691391488)]} 0 1
[junit4:junit4]   2> 7020 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[73 (1417014290693488640)]} 0 1
[junit4:junit4]   2> 7024 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7025 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[10074 (1417014290694537216)]} 0 5
[junit4:junit4]   2> 7028 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10075 (1417014290701877248)]} 0 1
[junit4:junit4]   2> 7034 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 7035 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7035 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[73 (1417014290696634368)]} 0 13
[junit4:junit4]   2> 7035 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10075 (1417014290705022976)]} 0 5
[junit4:junit4]   2> 7038 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10068 (-1417014290713411584)]} 0 0
[junit4:junit4]   2> 7038 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[74 (1417014290712363008)]} 0 1
[junit4:junit4]   2> 7042 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7042 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10068 (-1417014290715508736)]} 0 2
[junit4:junit4]   2> 7043 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7044 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[74 (1417014290716557312)]} 0 3
[junit4:junit4]   2> 7045 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[10076 (1417014290719703040)]} 0 1
[junit4:junit4]   2> 7046 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[75 (1417014290720751616)]} 0 1
[junit4:junit4]   2> 7050 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7050 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[75 (1417014290722848768)]} 0 3
[junit4:junit4]   2> 7053 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[68 (-1417014290729140224)]} 0 1
[junit4:junit4]   2> 7057 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7057 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[68 (-1417014290731237376)]} 0 2
[junit4:junit4]   2> 7059 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[76 (1417014290735431680)]} 0 0
[junit4:junit4]   2> 7063 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7064 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[76 (1417014290737528832)]} 0 3
[junit4:junit4]   2> 7066 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7066 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10076 (1417014290721800192)]} 0 20
[junit4:junit4]   2> 7068 T1528 C68 P48467 /update {wt=javabin&version=2} {add=[10077 (1417014290744868864)]} 0 0
[junit4:junit4]   2> 7073 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7074 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10077 (1417014290746966016)]} 0 4
[junit4:junit4]   2> 7075 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10071 (-1417014290752208896)]} 0 0
[junit4:junit4]   2> 7079 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7079 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10071 (-1417014290754306048)]} 0 2
[junit4:junit4]   2> 7081 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[77 (1417014290742771712)]} 0 16
[junit4:junit4]   2> 7082 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10078 (1417014290758500352)]} 0 1
[junit4:junit4]   2> 7086 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7086 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[77 (1417014290760597504)]} 0 3
[junit4:junit4]   2> 7086 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7087 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10078 (1417014290761646080)]} 0 3
[junit4:junit4]   2> 7088 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[71 (-1417014290765840384)]} 0 0
[junit4:junit4]   2> 7088 T1529 C68 P48467 /update {wt=javabin&version=2} {delete=[10073 (-1417014290765840385)]} 0 0
[junit4:junit4]   2> 7091 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7091 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7091 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[71 (-1417014290767937536)]} 0 2
[junit4:junit4]   2> 7092 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10073 (-1417014290767937537)]} 0 2
[junit4:junit4]   2> 7094 T1527 C68 P48467 /update {wt=javabin&version=2} {add=[78 (1417014290771083264)]} 0 1
[junit4:junit4]   2> 7095 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10079 (1417014290772131840)]} 0 1
[junit4:junit4]   2> 7099 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7099 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10079 (1417014290774228993)]} 0 3
[junit4:junit4]   2> 7101 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[10076 (-1417014290779471872)]} 0 0
[junit4:junit4]   2> 7104 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7105 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[10076 (-1417014290781569024)]} 0 2
[junit4:junit4]   2> 7107 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10080 (1417014290784714752)]} 0 1
[junit4:junit4]   2> 7112 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7112 T1545 C67 P53780 /update {wt=javabin&version=2} {add=[10080 (1417014290787860480)]} 0 3
[junit4:junit4]   2> 7114 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[10077 (-1417014290793103360)]} 0 0
[junit4:junit4]   2> 7117 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7117 T1543 C67 P53780 /update {wt=javabin&version=2} {delete=[10077 (-1417014290794151936)]} 0 2
[junit4:junit4]   2> 7120 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10081 (1417014290798346240)]} 0 1
[junit4:junit4]   2> 7124 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7125 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[10081 (1417014290801491968)]} 0 4
[junit4:junit4]   2> 7127 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[10080 (-1417014290806734848)]} 0 0
[junit4:junit4]   2> 7130 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7130 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[10080 (-1417014290807783424)]} 0 2
[junit4:junit4]   2> 7133 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[10082 (1417014290811977728)]} 0 1
[junit4:junit4]   2> 7217 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=119 
[junit4:junit4]   2> 7218 T1544 C67 P53780 /update {wt=javabin&version=2} {add=[78 (1417014290774228992)]} 0 122
[junit4:junit4]   2> 7220 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[73 (-1417014290904252416)]} 0 0
[junit4:junit4]   2> 7221 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=34 
[junit4:junit4]   2> 7221 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10082 (1417014290814074880)]} 0 87
[junit4:junit4]   2> 7223 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[10081 (-1417014290907398144)]} 0 0
[junit4:junit4]   2> 7224 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7224 T1545 C67 P53780 /update {wt=javabin&version=2} {delete=[73 (-1417014290906349568)]} 0 2
[junit4:junit4]   2> 7226 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7226 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[79 (1417014290910543872)]} 0 0
[junit4:junit4]   2> 7226 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[10081 (-1417014290909495296)]} 0 2
[junit4:junit4]   2> 7229 T1530 C68 P48467 /update {wt=javabin&version=2} {add=[10083 (1417014290913689600)]} 0 0
[junit4:junit4]   2> 7272 T1556 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=41 
[junit4:junit4]   2> 7273 T1546 C67 P53780 /update {wt=javabin&version=2} {add=[79 (1417014290912641024)]} 0 45
[junit4:junit4]   2> 7273 T1555 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=40 
[junit4:junit4]   2> 7274 T1543 C67 P53780 /update {wt=javabin&version=2} {add=[10083 (1417014290915786752)]} 0 43
[junit4:junit4]   2> 7275 T1527 C68 P48467 /update {wt=javabin&version=2} {delete=[76 (-1417014290961924096)]} 0 0
[junit4:junit4]   2> 7276 T1528 C68 P48467 /update {wt=javabin&version=2} {delete=[10082 (-1417014290962972672)]} 0 0
[junit4:junit4]   2> 7280 T1558 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7280 T1544 C67 P53780 /update {wt=javabin&version=2} {delete=[76 (-1417014290965069824)]} 0 2
[junit4:junit4]   2> 7280 T1557 C66 P38110 REQ /update {distrib.from=http://127.0.0.1:53780/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7281 T1546 C67 P53780 /update {wt=javabin&version=2} {delete=[10082 (-1417014290966118400)]} 0 3
[junit4:junit4]   2> 7283 T1529 C68 P48467 /update {wt=javabin&version=2} {add=[80 (1417014290969264128)]} 0 1
[junit4:junit4]   2> 7287 T1556 C66 P38110 REQ /update {distrib.from=http://127

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

2> 	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
[junit4:junit4]   2> 	at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
[junit4:junit4]   2> 	at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:439)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   2> 
[junit4:junit4]   2> 1166337 T5763 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ed8b6b name:ZooKeeperConnection Watcher:127.0.0.1:55209/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 1166338 T5761 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1bda01b name:ZooKeeperConnection Watcher:127.0.0.1:55209/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 1166338 T1661 oaz.ClientCnxn$EventThread.run SEVERE Event thread exiting due to interruption java.lang.InterruptedException
[junit4:junit4]   2> 	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
[junit4:junit4]   2> 	at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
[junit4:junit4]   2> 	at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:439)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   2> 
[junit4:junit4]   2> 1166339 T5761 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed
[junit4:junit4]   2> 1166338 T5763 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed
[junit4:junit4]   2> 1166338 T1548 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1166340 T5761 oaz.ClientCnxn$EventThread.run SEVERE Event thread exiting due to interruption java.lang.InterruptedException
[junit4:junit4]   2> 	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
[junit4:junit4]   2> 	at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
[junit4:junit4]   2> 	at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:439)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   2> 
[junit4:junit4]   2> 1166339 T1661 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1166340 T5763 oaz.ClientCnxn$EventThread.run SEVERE Event thread exiting due to interruption java.lang.InterruptedException
[junit4:junit4]   2> 	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
[junit4:junit4]   2> 	at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
[junit4:junit4]   2> 	at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:439)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4:junit4]   2> 
[junit4:junit4]   2> 1166341 T5761 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1166342 T5763 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1166342 T1511 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated.
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=de_CH, timezone=Pacific/Wallis
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic i386/Oracle Corporation 1.7.0_07 (32-bit)/cpus=8,threads=2,free=61762840,total=486866944
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestArbitraryIndexDir, TestSolrCoreProperties, DocumentBuilderTest, TestDFRSimilarityFactory, TestUtils, SolrCoreTest, ClusterStateTest, TestCodecSupport, TestStressVersions, TestCSVResponseWriter, TestHashPartitioner, IndexSchemaRuntimeFieldTest, MultiTermTest, ConvertedLegacyTest, TestPluginEnable, XmlUpdateRequestHandlerTest, TestUpdate, TestStressRecovery, TestDocSet, QueryEqualityTest, TermVectorComponentDistributedTest, ZkNodePropsTest, DocumentAnalysisRequestHandlerTest, TestSurroundQueryParser, LeaderElectionTest, IndexReaderFactoryTest, SpellingQueryConverterTest, BasicFunctionalityTest, URLClassifyProcessorTest, TestReplicationHandler, TestQuerySenderListener, OverseerTest, UUIDFieldTest, OutputWriterTest, RAMDirectoryFactoryTest, TestSort, DisMaxRequestHandlerTest, PrimUtilsTest, TestBinaryField, ReturnFieldsTest, TestValueSourceCache, SoftAutoCommitTest, DirectUpdateHandlerTest, OpenExchangeRatesOrgProviderTest, TestSolrDeletionPolicy1, TestCSVLoader, TestLRUCache, ShowFileRequestHandlerTest, SignatureUpdateProcessorFactoryTest, TestPropInject, IndexSchemaTest, PeerSyncTest, TestDistributedGrouping, RecoveryZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.seed=A65B01B51C5E8401 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de_CH -Dtests.timezone=Pacific/Wallis -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   0.00s J1 | RecoveryZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.cloud.RecoveryZkTest: 
[junit4:junit4]    >    1) Thread[id=5761, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=BLOCKED, group=TGRP-RecoveryZkTest]
[junit4:junit4]    >         at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:70)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]    >    2) Thread[id=5763, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=BLOCKED, group=TGRP-RecoveryZkTest]
[junit4:junit4]    >         at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:70)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]    >    3) Thread[id=1548, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=TIMED_WAITING, group=TGRP-RecoveryZkTest]
[junit4:junit4]    >         at java.lang.Thread.sleep(Native Method)
[junit4:junit4]    >         at org.apache.solr.cloud.ZkController.registerAllCoresAsDown(ZkController.java:275)
[junit4:junit4]    >         at org.apache.solr.cloud.ZkController.access$100(ZkController.java:82)
[junit4:junit4]    >         at org.apache.solr.cloud.ZkController$1.command(ZkController.java:190)
[junit4:junit4]    >         at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:116)
[junit4:junit4]    >         at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:46)
[junit4:junit4]    >         at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:90)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]    >    4) Thread[id=1661, name=TEST-RecoveryZkTest.testDistribSearch-seed#[A65B01B51C5E8401]-EventThread, state=TIMED_WAITING, group=TGRP-RecoveryZkTest]
[junit4:junit4]    >         at java.lang.Thread.sleep(Native Method)
[junit4:junit4]    >         at org.apache.solr.cloud.ZkController.registerAllCoresAsDown(ZkController.java:275)
[junit4:junit4]    >         at org.apache.solr.cloud.ZkController.access$100(ZkController.java:82)
[junit4:junit4]    >         at org.apache.solr.cloud.ZkController$1.command(ZkController.java:190)
[junit4:junit4]    >         at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:116)
[junit4:junit4]    >         at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:46)
[junit4:junit4]    >         at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:90)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([A65B01B51C5E8401]:0)
[junit4:junit4] Completed on J1 in 1166.38s, 1 test, 2 errors <<< FAILURES!

[...truncated 23 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1159: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:827: There were test failures: 232 suites, 961 tests, 1 suite-level error, 1 error, 7 ignored (2 assumptions)

Total time: 37 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_07 -client -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message