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/jrockit-jdk1.6.0_33-R28.2.4-4.1.0) - Build # 1933 - Failure!
Date Mon, 22 Oct 2012 21:01:22 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/1933/
Java: 32bit/jrockit-jdk1.6.0_33-R28.2.4-4.1.0 -XnoOpt

1 tests failed.
REGRESSION:  org.apache.solr.cloud.RecoveryZkTest.testDistribSearch

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:37063/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([85612DDEDD4AF775:487A3C6AA159749]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	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:738)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:774)
	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:683)
	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:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:44)
	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:56)
	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:662)




Build Log:
[...truncated 7893 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 21 T707 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-1350939101768
[junit4:junit4]   2> 22 T707 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 23 T708 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 24 T708 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 24 T708 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 123 T707 oasc.ZkTestServer.run start zk server on port:41986
[junit4:junit4]   2> 123 T707 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41986 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b1c3e38
[junit4:junit4]   2> 124 T713 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41986
[junit4:junit4]   2> 124 T707 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 124 T713 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41986, initiating session
[junit4:junit4]   2> 125 T709 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54970
[junit4:junit4]   2> 125 T709 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54970
[junit4:junit4]   2> 125 T711 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 133 T711 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a8a3eca4b0000 with negotiated timeout 10000 for client /127.0.0.1:54970
[junit4:junit4]   2> 133 T713 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41986, sessionid = 0x13a8a3eca4b0000, negotiated timeout = 10000
[junit4:junit4]   2> 133 T714 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b1c3e38 name:ZooKeeperConnection Watcher:127.0.0.1:41986 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 133 T707 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 134 T707 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 135 T712 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a8a3eca4b0000
[junit4:junit4]   2> 135 T714 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 135 T707 oaz.ZooKeeper.close Session: 0x13a8a3eca4b0000 closed
[junit4:junit4]   2> 136 T709 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54970 which had sessionid 0x13a8a3eca4b0000
[junit4:junit4]   2> 136 T707 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41986/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b219024
[junit4:junit4]   2> 137 T715 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41986
[junit4:junit4]   2> 137 T707 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 137 T715 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41986, initiating session
[junit4:junit4]   2> 137 T709 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54971
[junit4:junit4]   2> 137 T709 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54971
[junit4:junit4]   2> 142 T711 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a8a3eca4b0001 with negotiated timeout 10000 for client /127.0.0.1:54971
[junit4:junit4]   2> 142 T715 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41986, sessionid = 0x13a8a3eca4b0001, negotiated timeout = 10000
[junit4:junit4]   2> 142 T716 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b219024 name:ZooKeeperConnection Watcher:127.0.0.1:41986/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 143 T707 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 143 T707 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 145 T707 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 146 T707 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 147 T707 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 148 T707 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> 149 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 161 T707 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> 162 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 191 T707 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> 193 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 197 T707 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> 198 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 200 T707 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> 200 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 202 T707 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> 203 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 207 T707 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 208 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 210 T707 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 211 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 212 T707 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> 213 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 214 T707 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> 214 T707 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 216 T712 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a8a3eca4b0001
[junit4:junit4]   2> 216 T716 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 216 T707 oaz.ZooKeeper.close Session: 0x13a8a3eca4b0001 closed
[junit4:junit4]   2> 216 T709 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54971 which had sessionid 0x13a8a3eca4b0001
[junit4:junit4]   2> 269 T707 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 287 T707 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:43679
[junit4:junit4]   2> 288 T707 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 288 T707 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-1350939101963
[junit4:junit4]   2> 289 T707 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-1350939101963/'
[junit4:junit4]   2> 303 T707 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 304 T707 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 304 T707 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-1350939101963
[junit4:junit4]   2> 304 T707 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-1350939101963/solr.xml
[junit4:junit4]   2> 305 T707 oasc.CoreContainer.<init> New CoreContainer 452334168
[junit4:junit4]   2> 305 T707 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-1350939101963/'
[junit4:junit4]   2> 305 T707 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-1350939101963/'
[junit4:junit4]   2> 320 T707 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 333 T707 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41986/solr
[junit4:junit4]   2> 333 T707 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41986/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b32e314
[junit4:junit4]   2> 334 T726 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41986
[junit4:junit4]   2> 334 T707 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 334 T709 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54972
[junit4:junit4]   2> 334 T726 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41986, initiating session
[junit4:junit4]   2> 335 T709 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54972
[junit4:junit4]   2> 336 T711 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a8a3eca4b0002 with negotiated timeout 5000 for client /127.0.0.1:54972
[junit4:junit4]   2> 336 T726 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41986, sessionid = 0x13a8a3eca4b0002, negotiated timeout = 5000
[junit4:junit4]   2> 337 T727 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b32e314 name:ZooKeeperConnection Watcher:127.0.0.1:41986/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 337 T707 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 338 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 338 T707 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 340 T707 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43679_solr
[junit4:junit4]   2> 340 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:43679_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:43679_solr
[junit4:junit4]   2> 342 T707 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43679_solr
[junit4:junit4]   2> 345 T707 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 345 T707 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 345 T707 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 346 T707 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 346 T707 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 346 T707 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 347 T707 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 347 T707 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 347 T707 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 348 T707 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 351 T707 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 364 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 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> 366 T707 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 368 T707 oasc.Overseer.start Overseer (id=88535144973664258-127.0.0.1:43679_solr-n_0000000000) starting
[junit4:junit4]   2> 368 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 372 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 373 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 373 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 374 T729 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 374 T707 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 376 T707 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 377 T728 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 382 T707 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-1350939101963/collection1
[junit4:junit4]   2> 383 T707 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 384 T707 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 384 T707 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 385 T707 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-1350939101963/collection1/'
[junit4:junit4]   2> 386 T707 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-1350939101963/collection1/lib/README' to classloader
[junit4:junit4]   2> 387 T707 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-1350939101963/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 428 T707 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 489 T707 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 490 T707 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 495 T707 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1034 T707 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1042 T707 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1045 T707 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1053 T707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1059 T707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1062 T707 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1064 T707 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1065 T707 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-1350939101963/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1350939101768/control/data/
[junit4:junit4]   2> 1065 T707 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1abf7cf1
[junit4:junit4]   2> 1072 T707 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1350939101768/control/data/index/
[junit4:junit4]   2> 1072 T707 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1350939101768/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1073 T707 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-1350939101768/control/data/index forceNew:false
[junit4:junit4]   2> 1078 T707 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350939101768/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1078 T707 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1079 T707 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1080 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1080 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1081 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1081 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1081 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1082 T707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1082 T707 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1083 T707 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1083 T707 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1084 T707 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1084 T707 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1084 T707 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1085 T707 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1085 T707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1086 T707 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1086 T707 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1087 T707 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1087 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1087 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1088 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1088 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1089 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1089 T707 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1090 T707 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1090 T707 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1093 T707 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1095 T707 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1095 T707 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> 1098 T707 oass.SolrIndexSearcher.<init> Opening Searcher@1ae33d7e main
[junit4:junit4]   2> 1098 T707 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1099 T707 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1099 T707 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1124 T707 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1153 T730 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ae33d7e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1157 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 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> 1158 T707 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1158 T707 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:43679/solr shardId:control_shard
[junit4:junit4]   2> 1159 T707 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1162 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 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> 1162 T707 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1163 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1383 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1384 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 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> 1522 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1664 T707 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1664 T707 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1665 T707 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:43679/solr/collection1/
[junit4:junit4]   2> 1665 T707 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1665 T707 oasc.SyncStrategy.syncToMe http://127.0.0.1:43679/solr/collection1/ has no replicas
[junit4:junit4]   2> 1666 T707 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:43679/solr/collection1/
[junit4:junit4]   2> 1666 T707 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1703 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2064 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2122 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2154 T707 oasc.ZkController.register We are http://127.0.0.1:43679/solr/collection1/ and leader is http://127.0.0.1:43679/solr/collection1/
[junit4:junit4]   2> 2154 T707 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:43679/solr
[junit4:junit4]   2> 2155 T707 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2172 T707 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2173 T707 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 2173 T707 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2174 T707 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2227 T707 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2229 T707 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:39664
[junit4:junit4]   2> 2229 T707 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2230 T707 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-1350939103923
[junit4:junit4]   2> 2230 T707 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-1350939103923/'
[junit4:junit4]   2> 2242 T707 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2243 T707 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2243 T707 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-1350939103923
[junit4:junit4]   2> 2243 T707 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-1350939103923/solr.xml
[junit4:junit4]   2> 2244 T707 oasc.CoreContainer.<init> New CoreContainer 451991038
[junit4:junit4]   2> 2244 T707 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-1350939103923/'
[junit4:junit4]   2> 2244 T707 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-1350939103923/'
[junit4:junit4]   2> 2258 T707 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2274 T707 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41986/solr
[junit4:junit4]   2> 2275 T707 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41986/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1acabc51
[junit4:junit4]   2> 2275 T740 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41986
[junit4:junit4]   2> 2276 T707 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2276 T740 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41986, initiating session
[junit4:junit4]   2> 2276 T709 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54973
[junit4:junit4]   2> 2276 T709 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54973
[junit4:junit4]   2> 2278 T711 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a8a3eca4b0003 with negotiated timeout 5000 for client /127.0.0.1:54973
[junit4:junit4]   2> 2278 T740 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41986, sessionid = 0x13a8a3eca4b0003, negotiated timeout = 5000
[junit4:junit4]   2> 2279 T741 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1acabc51 name:ZooKeeperConnection Watcher:127.0.0.1:41986/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2280 T707 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2281 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2282 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2282 T707 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39664_solr
[junit4:junit4]   2> 2283 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:39664_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:39664_solr
[junit4:junit4]   2> 2284 T707 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39664_solr
[junit4:junit4]   2> 2285 T727 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2286 T707 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2287 T707 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2288 T707 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2288 T707 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2289 T707 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2290 T707 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2290 T707 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2291 T707 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2291 T707 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2292 T707 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2297 T707 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2301 T707 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-1350939103923/collection1
[junit4:junit4]   2> 2302 T707 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2302 T707 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2303 T707 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2304 T707 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-1350939103923/collection1/'
[junit4:junit4]   2> 2304 T707 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-1350939103923/collection1/lib/README' to classloader
[junit4:junit4]   2> 2305 T707 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-1350939103923/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2341 T707 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2403 T707 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2404 T707 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2410 T707 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2643 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2646 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2646 T741 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2970 T707 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2981 T707 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2987 T707 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2997 T707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3001 T707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3006 T707 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3007 T707 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3008 T707 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-1350939103923/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty1/
[junit4:junit4]   2> 3009 T707 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1abf7cf1
[junit4:junit4]   2> 3010 T707 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty1/index/
[junit4:junit4]   2> 3011 T707 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3011 T707 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-1350939101768/jetty1/index forceNew:false
[junit4:junit4]   2> 3033 T707 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3034 T707 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3035 T707 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3037 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3038 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3038 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3039 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3040 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3040 T707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3041 T707 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3042 T707 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3043 T707 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3044 T707 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3044 T707 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3045 T707 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3046 T707 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3047 T707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3047 T707 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3048 T707 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3049 T707 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3050 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3051 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3052 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3052 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3053 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3054 T707 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3054 T707 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3055 T707 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3058 T707 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3060 T707 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3061 T707 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> 3065 T707 oass.SolrIndexSearcher.<init> Opening Searcher@1afd74b5 main
[junit4:junit4]   2> 3065 T707 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3066 T707 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3067 T707 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3113 T707 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3150 T742 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1afd74b5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3650 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3671 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3671 T741 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4159 T707 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4160 T707 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:39664/solr shardId:shard1
[junit4:junit4]   2> 4161 T707 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 4166 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0003 type:delete cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 4166 T707 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4167 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4168 T707 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4169 T707 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4169 T707 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:39664/solr/collection1/
[junit4:junit4]   2> 4170 T707 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4170 T707 oasc.SyncStrategy.syncToMe http://127.0.0.1:39664/solr/collection1/ has no replicas
[junit4:junit4]   2> 4171 T707 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:39664/solr/collection1/
[junit4:junit4]   2> 4171 T707 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 4174 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4176 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4176 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4177 T741 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4679 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4881 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4881 T741 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4929 T707 oasc.ZkController.register We are http://127.0.0.1:39664/solr/collection1/ and leader is http://127.0.0.1:39664/solr/collection1/
[junit4:junit4]   2> 4930 T707 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39664/solr
[junit4:junit4]   2> 4930 T707 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4952 T707 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4953 T707 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4953 T707 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4954 T707 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5007 T707 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5008 T707 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:37063
[junit4:junit4]   2> 5009 T707 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5009 T707 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-1350939106703
[junit4:junit4]   2> 5009 T707 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-1350939106703/'
[junit4:junit4]   2> 5022 T707 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5023 T707 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5023 T707 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-1350939106703
[junit4:junit4]   2> 5024 T707 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-1350939106703/solr.xml
[junit4:junit4]   2> 5024 T707 oasc.CoreContainer.<init> New CoreContainer 454453272
[junit4:junit4]   2> 5024 T707 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-1350939106703/'
[junit4:junit4]   2> 5024 T707 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-1350939106703/'
[junit4:junit4]   2> 5038 T707 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5050 T707 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41986/solr
[junit4:junit4]   2> 5050 T707 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41986/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1af1ada8
[junit4:junit4]   2> 5051 T752 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41986
[junit4:junit4]   2> 5051 T707 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5052 T752 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41986, initiating session
[junit4:junit4]   2> 5052 T709 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54974
[junit4:junit4]   2> 5052 T709 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54974
[junit4:junit4]   2> 5063 T711 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a8a3eca4b0004 with negotiated timeout 5000 for client /127.0.0.1:54974
[junit4:junit4]   2> 5063 T752 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41986, sessionid = 0x13a8a3eca4b0004, negotiated timeout = 5000
[junit4:junit4]   2> 5063 T753 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1af1ada8 name:ZooKeeperConnection Watcher:127.0.0.1:41986/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5064 T707 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5064 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5065 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5066 T707 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37063_solr
[junit4:junit4]   2> 5066 T712 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a8a3eca4b0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37063_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37063_solr
[junit4:junit4]   2> 5067 T707 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37063_solr
[junit4:junit4]   2> 5068 T727 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5068 T741 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5068 T707 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5069 T707 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5069 T707 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5069 T707 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5070 T707 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5070 T707 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5070 T707 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5071 T707 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5071 T707 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5071 T707 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5077 T707 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5082 T707 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-1350939106703/collection1
[junit4:junit4]   2> 5082 T707 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5083 T707 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5083 T707 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5084 T707 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-1350939106703/collection1/'
[junit4:junit4]   2> 5085 T707 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-1350939106703/collection1/lib/README' to classloader
[junit4:junit4]   2> 5085 T707 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-1350939106703/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5123 T707 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5181 T707 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5182 T707 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5196 T707 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5386 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5389 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5389 T753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5389 T741 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5707 T707 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5715 T707 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5719 T707 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5728 T707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5731 T707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5734 T707 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5735 T707 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5736 T707 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-1350939106703/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty2/
[junit4:junit4]   2> 5736 T707 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1abf7cf1
[junit4:junit4]   2> 5737 T707 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty2/index/
[junit4:junit4]   2> 5738 T707 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5738 T707 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-1350939101768/jetty2/index forceNew:false
[junit4:junit4]   2> 5763 T707 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5763 T707 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5764 T707 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5765 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5766 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5766 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5766 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5767 T707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5767 T707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5768 T707 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5768 T707 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5769 T707 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5769 T707 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5770 T707 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5770 T707 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5771 T707 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5771 T707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5772 T707 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5772 T707 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5773 T707 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5773 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5774 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5775 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5775 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5776 T707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5776 T707 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5777 T707 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5777 T707 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5780 T707 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5782 T707 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5782 T707 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> 5785 T707 oass.SolrIndexSearcher.<init> Opening Searcher@1b0bb31f main
[junit4:junit4]   2> 5786 T707 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5786 T707 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5787 T707 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5792 T707 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5847 T754 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b0bb31f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5892 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5894 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5895 T741 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5895 T753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6353 T707 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6353 T707 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:37063/solr shardId:shard1
[junit4:junit4]   2> 6373 T707 oasc.ZkController.register We are http://127.0.0.1:37063/solr/collection1/ and leader is http://127.0.0.1:39664/solr/collection1/
[junit4:junit4]   2> 6374 T707 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37063/solr
[junit4:junit4]   2> 6374 T707 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 6375 T707 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C30 name=collection1 org.apache.solr.core.SolrCore@1afce9b6 url=http://127.0.0.1:37063/solr/collection1 node=127.0.0.1:37063_solr
[junit4:junit4]   2> 6375 T755 C30 P37063 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 6376 T755 C30 P37063 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 6376 T707 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6392 T755 C30 P37063 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:39664/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 6392 T755 C30 P37063 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:37063/solr START replicas=[http://127.0.0.1:39664/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 6392 T707 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 6393 T755 C30 P37063 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 6393 T707 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6393 T755 C30 P37063 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 6394 T755 C30 P37063 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 6394 T707 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6394 T755 C30 P37063 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6397 T728 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6398 T707 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41986/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b109977
[junit4:junit4]   2> ASYNC  NEW_CORE C31 name=collection1 org.apache.solr.core.SolrCore@1af3cf84 url=http://127.0.0.1:39664/solr/collection1 node=127.0.0.1:39664_solr
[junit4:junit4]   2> 6399 T733 C31 P39664 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 6400 T757 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41986
[junit4:junit4]   2> 6400 T707 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6400 T709 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54977
[junit4:junit4]   2> 6400 T757 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41986, initiating session
[junit4:junit4]   2> 6400 T709 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54977
[junit4:junit4]   2> 6432 T711 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a8a3eca4b0005 with negotiated timeout 10000 for client /127.0.0.1:54977
[junit4:junit4]   2> 6432 T757 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41986, sessionid = 0x13a8a3eca4b0005, negotiated timeout = 10000
[junit4:junit4]   2> 6433 T758 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b109977 name:ZooKeeperConnection Watcher:127.0.0.1:41986/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6433 T707 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6452 T707 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6453 T741 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6453 T753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6453 T727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6454 T707 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 6454 T707 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 6457 T707 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C32 name=collection1 org.apache.solr.core.SolrCore@1ad02cba url=http://127.0.0.1:43679/solr/collection1 node=127.0.0.1:43679_solr
[junit4:junit4]   2> 6468 T720 C32 P43679 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350939101768/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6469 T720 C32 P43679 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6471 T720 C32 P43679 /update {wt=javabin&version=2} {add=[1 (1416562326333554688)]} 0 5
[junit4:junit4]   2> 6471 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10001 (1416562326333554689)]} 0 5
[junit4:junit4]   2> 6472 T759 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41986/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b21b850
[junit4:junit4]   2> 6473 T761 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41986
[junit4:junit4]   2> 6473 T759 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6473 T761 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41986, initiating session
[junit4:junit4]   2> 6473 T709 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54981
[junit4:junit4]   2> 6473 T709 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54981
[junit4:junit4]   2> 6475 T711 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a8a3eca4b0006 with negotiated timeout 10000 for client /127.0.0.1:54981
[junit4:junit4]   2> 6475 T761 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41986, sessionid = 0x13a8a3eca4b0006, negotiated timeout = 10000
[junit4:junit4]   2> 6475 T762 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b21b850 name:ZooKeeperConnection Watcher:127.0.0.1:41986/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6476 T759 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6476 T759 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6480 T735 C31 P39664 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6481 T735 C31 P39664 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2>  C30_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:37063_solr, base_url=http://127.0.0.1:37063/solr}
[junit4:junit4]   2> 6490 T745 C30 P37063 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6490 T745 C30 P37063 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6492 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 6493 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6493 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10001 (1416562326345089025)]} 0 15
[junit4:junit4]   2> 6493 T735 C31 P39664 /update {wt=javabin&version=2} {add=[1 (1416562326345089024)]} 0 15
[junit4:junit4]   2> 6495 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10001 (-1416562326362914816)]} 0 0
[junit4:junit4]   2> 6495 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[1 (-1416562326362914817)]} 0 0
[junit4:junit4]   2> 6499 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6499 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6500 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[1 (-1416562326365011969)]} 0 3
[junit4:junit4]   2> 6500 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10001 (-1416562326365011968)]} 0 3
[junit4:junit4]   2> 6502 T720 C32 P43679 /update {wt=javabin&version=2} {add=[2 (1416562326369206272)]} 0 1
[junit4:junit4]   2> 6502 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10002 (1416562326370254848)]} 0 0
[junit4:junit4]   2> 6506 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6507 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6507 T735 C31 P39664 /update {wt=javabin&version=2} {add=[2 (1416562326372352000)]} 0 3
[junit4:junit4]   2> 6507 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10002 (1416562326372352001)]} 0 3
[junit4:junit4]   2> 6509 T719 C32 P43679 /update {wt=javabin&version=2} {add=[3 (1416562326377594880)]} 0 0
[junit4:junit4]   2> 6510 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10003 (1416562326377594881)]} 0 1
[junit4:junit4]   2> 6513 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6514 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6514 T736 C31 P39664 /update {wt=javabin&version=2} {add=[3 (1416562326379692032)]} 0 3
[junit4:junit4]   2> 6514 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10003 (1416562326379692033)]} 0 3
[junit4:junit4]   2> 6516 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[2 (-1416562326384934912)]} 0 0
[junit4:junit4]   2> 6516 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10002 (-1416562326384934913)]} 0 0
[junit4:junit4]   2> 6519 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6520 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6520 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[2 (-1416562326387032064)]} 0 2
[junit4:junit4]   2> 6520 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10002 (-1416562326387032065)]} 0 2
[junit4:junit4]   2> 6522 T719 C32 P43679 /update {wt=javabin&version=2} {add=[4 (1416562326391226368)]} 0 0
[junit4:junit4]   2> 6522 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10004 (1416562326391226369)]} 0 0
[junit4:junit4]   2> 6526 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6526 T736 C31 P39664 /update {wt=javabin&version=2} {add=[4 (1416562326393323520)]} 0 2
[junit4:junit4]   2> 6526 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6527 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10004 (1416562326393323521)]} 0 3
[junit4:junit4]   2> 6528 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[3 (-1416562326397517824)]} 0 0
[junit4:junit4]   2> 6529 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10003 (-1416562326398566400)]} 0 0
[junit4:junit4]   2> 6531 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6532 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[3 (-1416562326399614976)]} 0 2
[junit4:junit4]   2> 6532 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6533 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10003 (-1416562326400663552)]} 0 2
[junit4:junit4]   2> 6534 T719 C32 P43679 /update {wt=javabin&version=2} {add=[5 (1416562326403809280)]} 0 0
[junit4:junit4]   2> 6538 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6538 T736 C31 P39664 /update {wt=javabin&version=2} {add=[5 (1416562326405906432)]} 0 2
[junit4:junit4]   2> 6540 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[5 (-1416562326410100736)]} 0 0
[junit4:junit4]   2> 6544 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6544 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[5 (-1416562326412197888)]} 0 2
[junit4:junit4]   2> 6545 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10005 (1416562326403809281)]} 0 11
[junit4:junit4]   2> 6547 T719 C32 P43679 /update {wt=javabin&version=2} {add=[6 (1416562326416392192)]} 0 1
[junit4:junit4]   2> 6552 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6552 T735 C31 P39664 /update {wt=javabin&version=2} {add=[6 (1416562326419537920)]} 0 3
[junit4:junit4]   2> 6554 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[6 (-1416562326424780800)]} 0 0
[junit4:junit4]   2> 6558 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6558 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[6 (-1416562326426877952)]} 0 2
[junit4:junit4]   2> 6559 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 6560 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10005 (1416562326417440768)]} 0 13
[junit4:junit4]   2> 6560 T719 C32 P43679 /update {wt=javabin&version=2} {add=[7 (1416562326431072256)]} 0 0
[junit4:junit4]   2> 6561 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10005 (-1416562326432120832)]} 0 0
[junit4:junit4]   2> 6564 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6565 T736 C31 P39664 /update {wt=javabin&version=2} {add=[7 (1416562326433169408)]} 0 3
[junit4:junit4]   2> 6565 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6565 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10005 (-1416562326434217984)]} 0 2
[junit4:junit4]   2> 6567 T720 C32 P43679 /update {wt=javabin&version=2} {add=[8 (1416562326437363712)]} 0 1
[junit4:junit4]   2> 6567 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10006 (1416562326438412288)]} 0 0
[junit4:junit4]   2> 6570 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6571 T735 C31 P39664 /update {wt=javabin&version=2} {add=[8 (1416562326439460864)]} 0 3
[junit4:junit4]   2> 6571 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6572 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10006 (1416562326440509440)]} 0 3
[junit4:junit4]   2> 6573 T719 C32 P43679 /update {wt=javabin&version=2} {add=[9 (1416562326444703744)]} 0 0
[junit4:junit4]   2> 6573 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10006 (-1416562326444703745)]} 0 0
[junit4:junit4]   2> 6577 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6577 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6577 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10006 (-1416562326446800897)]} 0 2
[junit4:junit4]   2> 6577 T736 C31 P39664 /update {wt=javabin&version=2} {add=[9 (1416562326446800896)]} 0 2
[junit4:junit4]   2> 6580 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10007 (1416562326450995200)]} 0 1
[junit4:junit4]   2> 6580 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10 (1416562326452043776)]} 0 1
[junit4:junit4]   2> 6593 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6593 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6593 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10007 (1416562326453092352)]} 0 12
[junit4:junit4]   2> 6593 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10 (1416562326462529536)]} 0 11
[junit4:junit4]   2> 6595 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[7 (-1416562326467772416)]} 0 0
[junit4:junit4]   2> 6596 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10008 (1416562326467772417)]} 0 1
[junit4:junit4]   2> 6598 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6599 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[7 (-1416562326469869568)]} 0 2
[junit4:junit4]   2> 6600 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6600 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10008 (1416562326469869569)]} 0 3
[junit4:junit4]   2> 6601 T719 C32 P43679 /update {wt=javabin&version=2} {add=[11 (1416562326474063872)]} 0 0
[junit4:junit4]   2> 6605 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6606 T736 C31 P39664 /update {wt=javabin&version=2} {add=[11 (1416562326476161024)]} 0 3
[junit4:junit4]   2> 6608 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[8 (-1416562326481403904)]} 0 0
[junit4:junit4]   2> 6611 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6612 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[8 (-1416562326483501056)]} 0 2
[junit4:junit4]   2> 6615 T719 C32 P43679 /update {wt=javabin&version=2} {add=[12 (1416562326487695360)]} 0 1
[junit4:junit4]   2> 6617 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10009 (1416562326475112448)]} 0 15
[junit4:junit4]   2> 6621 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6622 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10009 (1416562326491889664)]} 0 4
[junit4:junit4]   2> 6624 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10010 (1416562326497132544)]} 0 1
[junit4:junit4]   2> 6628 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6628 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10010 (1416562326499229696)]} 0 3
[junit4:junit4]   2> 6630 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10007 (-1416562326504472576)]} 0 0
[junit4:junit4]   2> 6633 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=14 
[junit4:junit4]   2> 6633 T736 C31 P39664 /update {wt=javabin&version=2} {add=[12 (1416562326490841088)]} 0 16
[junit4:junit4]   2> 6633 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6634 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10007 (-1416562326506569728)]} 0 2
[junit4:junit4]   2> 6635 T720 C32 P43679 /update {wt=javabin&version=2} {add=[13 (1416562326509715456)]} 0 0
[junit4:junit4]   2> 6636 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10011 (1416562326509715457)]} 0 1
[junit4:junit4]   2> 6640 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6641 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10011 (1416562326512861184)]} 0 3
[junit4:junit4]   2> 6642 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10008 (-1416562326517055488)]} 0 0
[junit4:junit4]   2> 6646 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6646 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10008 (-1416562326519152640)]} 0 2
[junit4:junit4]   2> 6649 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10012 (1416562326523346944)]} 0 0
[junit4:junit4]   2> 6653 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6653 T735 C31 P39664 /update {wt=javabin&version=2} {add=[13 (1416562326511812608)]} 0 16
[junit4:junit4]   2> 6653 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6654 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10012 (1416562326525444096)]} 0 4
[junit4:junit4]   2> 6655 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[11 (-1416562326530686976)]} 0 0
[junit4:junit4]   2> 6656 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10013 (1416562326531735552)]} 0 0
[junit4:junit4]   2> 6658 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6659 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[11 (-1416562326532784128)]} 0 2
[junit4:junit4]   2> 6660 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6661 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10013 (1416562326533832704)]} 0 3
[junit4:junit4]   2> 6661 T720 C32 P43679 /update {wt=javabin&version=2} {add=[14 (1416562326536978432)]} 0 0
[junit4:junit4]   2> 6663 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10011 (-1416562326539075584)]} 0 0
[junit4:junit4]   2> 6665 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6665 T735 C31 P39664 /update {wt=javabin&version=2} {add=[14 (1416562326539075584)]} 0 2
[junit4:junit4]   2> 6666 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6666 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10011 (-1416562326540124160)]} 0 2
[junit4:junit4]   2> 6667 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[12 (-1416562326543269888)]} 0 0
[junit4:junit4]   2> 6668 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10014 (1416562326544318464)]} 0 0
[junit4:junit4]   2> 6670 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6671 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[12 (-1416562326545367040)]} 0 2
[junit4:junit4]   2> 6672 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6672 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10014 (1416562326546415616)]} 0 2
[junit4:junit4]   2> 6673 T720 C32 P43679 /update {wt=javabin&version=2} {add=[15 (1416562326548512768)]} 0 1
[junit4:junit4]   2> 6674 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10012 (-1416562326550609920)]} 0 0
[junit4:junit4]   2> 6677 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6677 T735 C31 P39664 /update {wt=javabin&version=2} {add=[15 (1416562326550609920)]} 0 3
[junit4:junit4]   2> 6677 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6678 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10012 (-1416562326552707072)]} 0 2
[junit4:junit4]   2> 6679 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[13 (-1416562326555852800)]} 0 0
[junit4:junit4]   2> 6682 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6683 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[13 (-1416562326557949952)]} 0 2
[junit4:junit4]   2> 6686 T719 C32 P43679 /update {wt=javabin&version=2} {add=[16 (1416562326561095680)]} 0 2
[junit4:junit4]   2> 6702 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10015 (1416562326556901376)]} 0 22
[junit4:junit4]   2> 6707 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6708 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10015 (1416562326582067200)]} 0 4
[junit4:junit4]   2> 6709 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=20 
[junit4:junit4]   2> 6709 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10013 (-1416562326587310080)]} 0 0
[junit4:junit4]   2> 6710 T736 C31 P39664 /update {wt=javabin&version=2} {add=[16 (1416562326565289984)]} 0 22
[junit4:junit4]   2> 6712 T720 C32 P43679 /update {wt=javabin&version=2} {add=[17 (1416562326590455808)]} 0 1
[junit4:junit4]   2> 6713 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6713 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10013 (-1416562326589407232)]} 0 2
[junit4:junit4]   2> 6715 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10016 (1416562326593601536)]} 0 0
[junit4:junit4]   2> 6716 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6716 T736 C31 P39664 /update {wt=javabin&version=2} {add=[17 (1416562326592552960)]} 0 2
[junit4:junit4]   2> 6719 T720 C32 P43679 /update {wt=javabin&version=2} {add=[18 (1416562326596747264)]} 0 1
[junit4:junit4]   2> 6721 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6721 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10016 (1416562326596747264)]} 0 3
[junit4:junit4]   2> 6723 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6723 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10017 (1416562326601990144)]} 0 0
[junit4:junit4]   2> 6728 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6728 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10017 (1416562326605135872)]} 0 3
[junit4:junit4]   2> 6730 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10018 (1416562326609330176)]} 0 0
[junit4:junit4]   2> 6731 T736 C31 P39664 /update {wt=javabin&version=2} {add=[18 (1416562326599892992)]} 0 10
[junit4:junit4]   2> 6735 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6735 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10018 (1416562326611427328)]} 0 3
[junit4:junit4]   2> 6737 T720 C32 P43679 /update {wt=javabin&version=2} {add=[19 (1416562326615621632)]} 0 1
[junit4:junit4]   2> 6738 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10019 (1416562326616670208)]} 0 1
[junit4:junit4]   2> 6741 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6742 T735 C31 P39664 /update {wt=javabin&version=2} {add=[19 (1416562326618767360)]} 0 3
[junit4:junit4]   2> 6744 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[19 (-1416562326622961664)]} 0 1
[junit4:junit4]   2> 6753 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 6753 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6754 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10019 (1416562326618767361)]} 0 15
[junit4:junit4]   2> 6754 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[19 (-1416562326625058816)]} 0 9
[junit4:junit4]   2> 6755 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10019 (-1416562326635544576)]} 0 0
[junit4:junit4]   2> 6756 T720 C32 P43679 /update {wt=javabin&version=2} {add=[20 (1416562326636593152)]} 0 1
[junit4:junit4]   2> 6759 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6759 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10019 (-1416562326637641728)]} 0 2
[junit4:junit4]   2> 6783 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6784 T736 C31 P39664 /update {wt=javabin&version=2} {add=[20 (1416562326638690304)]} 0 26
[junit4:junit4]   2> 6786 T719 C32 P43679 /update {wt=javabin&version=2} {add=[21 (1416562326668050432)]} 0 1
[junit4:junit4]   2> 6791 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10020 (1416562326641836032)]} 0 30
[junit4:junit4]   2> 6822 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=31 
[junit4:junit4]   2> 6828 T736 C31 P39664 /update {wt=javabin&version=2} {add=[21 (1416562326670147584)]} 0 40
[junit4:junit4]   2> 6831 T720 C32 P43679 /update {wt=javabin&version=2} {add=[22 (1416562326714187776)]} 0 1
[junit4:junit4]   2> 6836 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6836 T736 C31 P39664 /update {wt=javabin&version=2} {add=[22 (1416562326717333504)]} 0 3
[junit4:junit4]   2> 6837 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 6837 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10020 (1416562326675390464)]} 0 44
[junit4:junit4]   2> 6838 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[20 (-1416562326722576384)]} 0 0
[junit4:junit4]   2> 6840 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10021 (1416562326723624960)]} 0 1
[junit4:junit4]   2> 6843 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6843 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[20 (-1416562326724673536)]} 0 3
[junit4:junit4]   2> 6844 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6847 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10021 (1416562326726770688)]} 0 5
[junit4:junit4]   2> 6849 T719 C32 P43679 /update {wt=javabin&version=2} {add=[23 (1416562326733062144)]} 0 1
[junit4:junit4]   2> 6849 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10022 (1416562326734110720)]} 0 0
[junit4:junit4]   2> 6853 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6853 T736 C31 P39664 /update {wt=javabin&version=2} {add=[23 (1416562326736207872)]} 0 2
[junit4:junit4]   2> 6855 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6855 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10022 (1416562326736207873)]} 0 4
[junit4:junit4]   2> 6855 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[22 (-1416562326740402176)]} 0 0
[junit4:junit4]   2> 6857 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10020 (-1416562326742499328)]} 0 0
[junit4:junit4]   2> 6859 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6859 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[22 (-1416562326742499328)]} 0 2
[junit4:junit4]   2> 6861 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6861 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10020 (-1416562326744596480)]} 0 2
[junit4:junit4]   2> 6861 T719 C32 P43679 /update {wt=javabin&version=2} {add=[24 (1416562326746693632)]} 0 0
[junit4:junit4]   2> 6863 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10023 (1416562326748790784)]} 0 0
[junit4:junit4]   2> 6866 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6866 T736 C31 P39664 /update {wt=javabin&version=2} {add=[24 (1416562326748790784)]} 0 3
[junit4:junit4]   2> 6867 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6868 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10023 (1416562326750887936)]} 0 3
[junit4:junit4]   2> 6868 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[23 (-1416562326754033664)]} 0 0
[junit4:junit4]   2> 6870 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10022 (-1416562326756130816)]} 0 1
[junit4:junit4]   2> 6871 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6872 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[23 (-1416562326756130816)]} 0 2
[junit4:junit4]   2> 6873 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6874 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10022 (-1416562326758227968)]} 0 3
[junit4:junit4]   2> 6874 T719 C32 P43679 /update {wt=javabin&version=2} {add=[25 (1416562326760325120)]} 0 0
[junit4:junit4]   2> 6876 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10024 (1416562326761373696)]} 0 1
[junit4:junit4]   2> 6878 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6879 T736 C31 P39664 /update {wt=javabin&version=2} {add=[25 (1416562326762422272)]} 0 3
[junit4:junit4]   2> 6880 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6880 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10024 (1416562326763470848)]} 0 3
[junit4:junit4]   2> 6882 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10023 (-1416562326768713728)]} 0 0
[junit4:junit4]   2> 6886 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6886 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10023 (-1416562326770810880)]} 0 2
[junit4:junit4]   2> 6890 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10025 (1416562326775005184)]} 0 2
[junit4:junit4]   2> 6913 T720 C32 P43679 /update {wt=javabin&version=2} {add=[26 (1416562326766616576)]} 0 33
[junit4:junit4]   2> 6917 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6918 T736 C31 P39664 /update {wt=javabin&version=2} {add=[26 (1416562326802268160)]} 0 4
[junit4:junit4]   2> 6920 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[25 (-1416562326808559616)]} 0 0
[junit4:junit4]   2> 6923 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6923 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[25 (-1416562326809608192)]} 0 2
[junit4:junit4]   2> 6925 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=31 
[junit4:junit4]   2> 6925 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10025 (1416562326779199488)]} 0 33
[junit4:junit4]   2> 6926 T720 C32 P43679 /update {wt=javabin&version=2} {add=[27 (1416562326813802496)]} 0 1
[junit4:junit4]   2> 6928 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10026 (1416562326815899648)]} 0 1
[junit4:junit4]   2> 6930 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6930 T735 C31 P39664 /update {wt=javabin&version=2} {add=[27 (1416562326815899648)]} 0 3
[junit4:junit4]   2> 6932 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6932 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10026 (1416562326819045376)]} 0 2
[junit4:junit4]   2> 6932 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[26 (-1416562326821142528)]} 0 0
[junit4:junit4]   2> 6934 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10025 (-1416562326823239680)]} 0 0
[junit4:junit4]   2> 6936 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6936 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[26 (-1416562326823239680)]} 0 2
[junit4:junit4]   2> 6937 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6938 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10025 (-1416562326825336832)]} 0 2
[junit4:junit4]   2> 6938 T720 C32 P43679 /update {wt=javabin&version=2} {add=[28 (1416562326827433984)]} 0 0
[junit4:junit4]   2> 6940 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10027 (1416562326829531136)]} 0 0
[junit4:junit4]   2> 6943 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6943 T735 C31 P39664 /update {wt=javabin&version=2} {add=[28 (1416562326829531136)]} 0 3
[junit4:junit4]   2> 6944 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6944 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10027 (1416562326831628288)]} 0 2
[junit4:junit4]   2> 6945 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[28 (-1416562326834774016)]} 0 0
[junit4:junit4]   2> 6946 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10026 (-1416562326835822592)]} 0 0
[junit4:junit4]   2> 6948 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6948 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[28 (-1416562326835822592)]} 0 2
[junit4:junit4]   2> 6949 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6950 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10026 (-1416562326837919744)]} 0 2
[junit4:junit4]   2> 6950 T720 C32 P43679 /update {wt=javabin&version=2} {add=[29 (1416562326840016896)]} 0 0
[junit4:junit4]   2> 6952 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10028 (1416562326842114048)]} 0 0
[junit4:junit4]   2> 6955 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6955 T735 C31 P39664 /update {wt=javabin&version=2} {add=[29 (1416562326842114048)]} 0 3
[junit4:junit4]   2> 6958 T719 C32 P43679 /update {wt=javabin&version=2} {add=[30 (1416562326847356928)]} 0 1
[junit4:junit4]   2> 6962 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6962 T735 C31 P39664 /update {wt=javabin&version=2} {add=[30 (1416562326849454080)]} 0 3
[junit4:junit4]   2> 6964 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[30 (-1416562326854696960)]} 0 0
[junit4:junit4]   2> 6968 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6968 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[30 (-1416562326856794112)]} 0 2
[junit4:junit4]   2> 6971 T719 C32 P43679 /update {wt=javabin&version=2} {add=[31 (1416562326860988416)]} 0 1
[junit4:junit4]   2> 6975 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6976 T735 C31 P39664 /update {wt=javabin&version=2} {add=[31 (1416562326864134144)]} 0 3
[junit4:junit4]   2> 6987 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6988 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10028 (1416562326844211200)]} 0 34
[junit4:junit4]   2> 6990 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10028 (-1416562326881959936)]} 0 0
[junit4:junit4]   2> 6993 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6994 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10028 (-1416562326884057088)]} 0 2
[junit4:junit4]   2> 6998 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10029 (1416562326888251392)]} 0 2
[junit4:junit4]   2> 7018 T719 C32 P43679 /update {wt=javabin&version=2} {add=[32 (1416562326869377024)]} 0 40
[junit4:junit4]   2> 7041 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=39 
[junit4:junit4]   2> 7041 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10029 (1416562326891397120)]} 0 42
[junit4:junit4]   2> 7044 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10030 (1416562326937534464)]} 0 1
[junit4:junit4]   2> 7049 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7049 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10030 (1416562326940680192)]} 0 3
[junit4:junit4]   2> 7051 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10030 (-1416562326945923072)]} 0 0
[junit4:junit4]   2> 7054 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7055 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10030 (-1416562326948020224)]} 0 2
[junit4:junit4]   2> 7057 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10031 (1416562326952214528)]} 0 1
[junit4:junit4]   2> 7061 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7062 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10031 (1416562326954311680)]} 0 3
[junit4:junit4]   2> 7064 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10032 (1416562326958505984)]} 0 1
[junit4:junit4]   2> 7067 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7068 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10032 (1416562326960603136)]} 0 3
[junit4:junit4]   2> 7070 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10033 (1416562326965846016)]} 0 0
[junit4:junit4]   2> 7074 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7074 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10033 (1416562326967943168)]} 0 2
[junit4:junit4]   2> 7076 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10031 (-1416562326972137472)]} 0 0
[junit4:junit4]   2> 7077 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7077 T735 C31 P39664 /update {wt=javabin&version=2} {add=[32 (1416562326913417216)]} 0 57
[junit4:junit4]   2> 7079 T719 C32 P43679 /update {wt=javabin&version=2} {add=[33 (1416562326975283200)]} 0 0
[junit4:junit4]   2> 7079 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7080 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10031 (-1416562326974234624)]} 0 2
[junit4:junit4]   2> 7082 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10034 (1416562326978428928)]} 0 0
[junit4:junit4]   2> 7084 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7084 T735 C31 P39664 /update {wt=javabin&version=2} {add=[33 (1416562326977380352)]} 0 3
[junit4:junit4]   2> 7086 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7086 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[31 (-1416562326982623232)]} 0 0
[junit4:junit4]   2> 7086 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10034 (1416562326980526080)]} 0 2
[junit4:junit4]   2> 7088 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10035 (1416562326984720384)]} 0 0
[junit4:junit4]   2> 7089 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7090 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[31 (-1416562326984720384)]} 0 2
[junit4:junit4]   2> 7092 T719 C32 P43679 /update {wt=javabin&version=2} {add=[34 (1416562326988914688)]} 0 0
[junit4:junit4]   2> 7092 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7093 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10035 (1416562326986817536)]} 0 3
[junit4:junit4]   2> 7095 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10035 (-1416562326992060416)]} 0 0
[junit4:junit4]   2> 7096 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7097 T735 C31 P39664 /update {wt=javabin&version=2} {add=[34 (1416562326991011840)]} 0 3
[junit4:junit4]   2> 7098 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7099 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10035 (-1416562326993108992)]} 0 3
[junit4:junit4]   2> 7101 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10036 (1416562326997303296)]} 0 1
[junit4:junit4]   2> 7159 T719 C32 P43679 /update {wt=javabin&version=2} {add=[35 (1416562326995206144)]} 0 61
[junit4:junit4]   2> 7164 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7164 T735 C31 P39664 /update {wt=javabin&version=2} {add=[35 (1416562327061266432)]} 0 3
[junit4:junit4]   2> 7164 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=58 
[junit4:junit4]   2> 7165 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10036 (1416562327001497600)]} 0 61
[junit4:junit4]   2> 7166 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[35 (-1416562327066509312)]} 0 0
[junit4:junit4]   2> 7167 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10037 (1416562327067557888)]} 0 0
[junit4:junit4]   2> 7169 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7170 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[35 (-1416562327068606464)]} 0 2
[junit4:junit4]   2> 7171 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7171 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10037 (1416562327069655040)]} 0 2
[junit4:junit4]   2> 7172 T720 C32 P43679 /update {wt=javabin&version=2} {add=[36 (1416562327072800768)]} 0 0
[junit4:junit4]   2> 7173 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10038 (1416562327073849344)]} 0 0
[junit4:junit4]   2> 7176 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7176 T735 C31 P39664 /update {wt=javabin&version=2} {add=[36 (1416562327074897920)]} 0 2
[junit4:junit4]   2> 7177 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7178 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10038 (1416562327075946496)]} 0 3
[junit4:junit4]   2> 7179 T719 C32 P43679 /update {wt=javabin&version=2} {add=[37 (1416562327079092224)]} 0 1
[junit4:junit4]   2> 7180 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10039 (1416562327081189376)]} 0 0
[junit4:junit4]   2> 7184 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7185 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10039 (1416562327083286528)]} 0 3
[junit4:junit4]   2> 7187 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10040 (1416562327088529408)]} 0 0
[junit4:junit4]   2> 7192 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7192 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10040 (1416562327090626560)]} 0 3
[junit4:junit4]   2> 7194 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10038 (-1416562327095869440)]} 0 0
[junit4:junit4]   2> 7198 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7199 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10038 (-1416562327097966592)]} 0 3
[junit4:junit4]   2> 7201 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10041 (1416562327103209472)]} 0 0
[junit4:junit4]   2> 7205 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7206 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10041 (1416562327105306624)]} 0 3
[junit4:junit4]   2> 7208 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10042 (1416562327110549504)]} 0 0
[junit4:junit4]   2> 7212 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7213 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10042 (1416562327112646656)]} 0 3
[junit4:junit4]   2> 7214 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10039 (-1416562327116840960)]} 0 0
[junit4:junit4]   2> 7218 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7218 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7218 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10039 (-1416562327119986688)]} 0 2
[junit4:junit4]   2> 7219 T736 C31 P39664 /update {wt=javabin&version=2} {add=[37 (1416562327081189376)]} 0 39
[junit4:junit4]   2> 7222 T719 C32 P43679 /update {wt=javabin&version=2} {add=[38 (1416562327124180993)]} 0 1
[junit4:junit4]   2> 7703 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=477 
[junit4:junit4]   2> 7704 T736 C31 P39664 /update {wt=javabin&version=2} {add=[38 (1416562327127326720)]} 0 480
[junit4:junit4]   2> 7706 T719 C32 P43679 /update {wt=javabin&version=2} {add=[39 (1416562327632740352)]} 0 0
[junit4:junit4]   2> 7711 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7711 T736 C31 P39664 /update {wt=javabin&version=2} {add=[39 (1416562327634837504)]} 0 3
[junit4:junit4]   2> 7713 T719 C32 P43679 /update {wt=javabin&version=2} {add=[40 (1416562327640080384)]} 0 0
[junit4:junit4]   2> 7718 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7718 T736 C31 P39664 /update {wt=javabin&version=2} {add=[40 (1416562327642177536)]} 0 3
[junit4:junit4]   2> 7720 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[38 (-1416562327647420416)]} 0 0
[junit4:junit4]   2> 7723 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7724 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[38 (-1416562327649517568)]} 0 2
[junit4:junit4]   2> 7726 T719 C32 P43679 /update {wt=javabin&version=2} {add=[41 (1416562327653711872)]} 0 0
[junit4:junit4]   2> 7731 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7731 T736 C31 P39664 /update {wt=javabin&version=2} {add=[41 (1416562327655809024)]} 0 3
[junit4:junit4]   2> 7734 T719 C32 P43679 /update {wt=javabin&version=2} {add=[42 (1416562327661051904)]} 0 1
[junit4:junit4]   2> 7738 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7739 T736 C31 P39664 /update {wt=javabin&version=2} {add=[42 (1416562327664197632)]} 0 3
[junit4:junit4]   2> 7741 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[39 (-1416562327668391936)]} 0 1
[junit4:junit4]   2> 7744 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7745 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[39 (-1416562327670489088)]} 0 3
[junit4:junit4]   2> 7747 T719 C32 P43679 /update {wt=javabin&version=2} {add=[43 (1416562327675731968)]} 0 0
[junit4:junit4]   2> 7750 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10043 (1416562327124180992)]} 0 529
[junit4:junit4]   2> 7752 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7752 T736 C31 P39664 /update {wt=javabin&version=2} {add=[43 (1416562327677829120)]} 0 3
[junit4:junit4]   2> 7755 T720 C32 P43679 /update {wt=javabin&version=2} {add=[44 (1416562327683072000)]} 0 1
[junit4:junit4]   2> 7764 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7765 T736 C31 P39664 /update {wt=javabin&version=2} {add=[44 (1416562327686217728)]} 0 8
[junit4:junit4]   2> 7767 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[42 (-1416562327696703488)]} 0 1
[junit4:junit4]   2> 7770 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7771 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[42 (-1416562327698800640)]} 0 2
[junit4:junit4]   2> 7773 T720 C32 P43679 /update {wt=javabin&version=2} {add=[45 (1416562327702994944)]} 0 0
[junit4:junit4]   2> 7778 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7779 T736 C31 P39664 /update {wt=javabin&version=2} {add=[45 (1416562327705092096)]} 0 4
[junit4:junit4]   2> 7781 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[43 (-1416562327711383552)]} 0 1
[junit4:junit4]   2> 7784 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7785 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[43 (-1416562327713480704)]} 0 2
[junit4:junit4]   2> 7787 T720 C32 P43679 /update {wt=javabin&version=2} {add=[46 (1416562327717675008)]} 0 0
[junit4:junit4]   2> 7792 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7792 T736 C31 P39664 /update {wt=javabin&version=2} {add=[46 (1416562327719772160)]} 0 3
[junit4:junit4]   2> 7842 T720 C32 P43679 /update {wt=javabin&version=2} {add=[47 (1416562327725015040)]} 0 48
[junit4:junit4]   2> 8126 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8127 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10043 (1416562327680974848)]} 0 375
[junit4:junit4]   2> 8130 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10044 (1416562328076288000)]} 0 1
[junit4:junit4]   2> 8155 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=21 
[junit4:junit4]   2> 8156 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10044 (1416562328079433728)]} 0 24
[junit4:junit4]   2> 8157 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10042 (-1416562328105648128)]} 0 0
[junit4:junit4]   2> 8161 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8161 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10042 (-1416562328107745280)]} 0 2
[junit4:junit4]   2> 8163 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10045 (1416562328111939584)]} 0 0
[junit4:junit4]   2> 8169 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 8169 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10045 (1416562328115085312)]} 0 4
[junit4:junit4]   2> 8172 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10043 (-1416562328120328192)]} 0 1
[junit4:junit4]   2> 8175 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8176 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10043 (-1416562328123473920)]} 0 2
[junit4:junit4]   2> 8179 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10046 (1416562328127668224)]} 0 0
[junit4:junit4]   2> 8183 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8184 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10046 (1416562328130813952)]} 0 3
[junit4:junit4]   2> 8187 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8187 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10047 (1416562328136056832)]} 0 1
[junit4:junit4]   2> 8188 T736 C31 P39664 /update {wt=javabin&version=2} {add=[47 (1416562327777443840)]} 0 344
[junit4:junit4]   2> 8191 T720 C32 P43679 /update {wt=javabin&version=2} {add=[48 (1416562328140251136)]} 0 1
[junit4:junit4]   2> 8192 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8193 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10047 (1416562328140251136)]} 0 3
[junit4:junit4]   2> 8195 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10048 (1416562328145494016)]} 0 0
[junit4:junit4]   2> 8196 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8196 T736 C31 P39664 /update {wt=javabin&version=2} {add=[48 (1416562328143396864)]} 0 3
[junit4:junit4]   2> 8198 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[48 (-1416562328148639744)]} 0 0
[junit4:junit4]   2> 8199 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8200 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10048 (1416562328147591168)]} 0 3
[junit4:junit4]   2> 8201 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10048 (-1416562328151785472)]} 0 0
[junit4:junit4]   2> 8202 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8202 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[48 (-1416562328150736896)]} 0 2
[junit4:junit4]   2> 8205 T720 C32 P43679 /update {wt=javabin&version=2} {add=[49 (1416562328154931200)]} 0 1
[junit4:junit4]   2> 8205 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8205 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10048 (-1416562328153882624)]} 0 2
[junit4:junit4]   2> 8207 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10049 (1416562328158076928)]} 0 0
[junit4:junit4]   2> 8209 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8210 T736 C31 P39664 /update {wt=javabin&version=2} {add=[49 (1416562328158076928)]} 0 4
[junit4:junit4]   2> 8212 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8212 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[49 (-1416562328163319808)]} 0 1
[junit4:junit4]   2> 8212 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10049 (1416562328160174080)]} 0 3
[junit4:junit4]   2> 8214 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10049 (-1416562328165416960)]} 0 0
[junit4:junit4]   2> 8215 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8216 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[49 (-1416562328165416960)]} 0 2
[junit4:junit4]   2> 8217 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8218 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10049 (-1416562328167514112)]} 0 2
[junit4:junit4]   2> 8218 T720 C32 P43679 /update {wt=javabin&version=2} {add=[50 (1416562328169611264)]} 0 0
[junit4:junit4]   2> 8223 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8223 T735 C31 P39664 /update {wt=javabin&version=2} {add=[50 (1416562328171708416)]} 0 3
[junit4:junit4]   2> 8226 T720 C32 P43679 /update {wt=javabin&version=2} {add=[51 (1416562328176951296)]} 0 1
[junit4:junit4]   2> 8243 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10050 (1416562328171708416)]} 0 23
[junit4:junit4]   2> 8249 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 8249 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10050 (1416562328197922816)]} 0 4
[junit4:junit4]   2> 8250 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=20 
[junit4:junit4]   2> 8251 T735 C31 P39664 /update {wt=javabin&version=2} {add=[51 (1416562328180097024)]} 0 23
[junit4:junit4]   2> 8252 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10051 (1416562328204214272)]} 0 1
[junit4:junit4]   2> 8253 T720 C32 P43679 /update {wt=javabin&version=2} {add=[52 (1416562328206311424)]} 0 1
[junit4:junit4]   2> 8255 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8256 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10051 (1416562328206311424)]} 0 3
[junit4:junit4]   2> 8257 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8257 T736 C31 P39664 /update {wt=javabin&version=2} {add=[52 (1416562328208408576)]} 0 2
[junit4:junit4]   2> 8258 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10052 (1416562328211554304)]} 0 0
[junit4:junit4]   2> 8259 T719 C32 P43679 /update {wt=javabin&version=2} {add=[53 (1416562328212602880)]} 0 0
[junit4:junit4]   2> 8262 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8262 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10052 (1416562328213651456)]} 0 2
[junit4:junit4]   2> 8265 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10053 (1416562328217845760)]} 0 1
[junit4:junit4]   2> 8269 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8269 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10053 (1416562328219942912)]} 0 3
[junit4:junit4]   2> 8272 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10054 (1416562328225185792)]} 0 1
[junit4:junit4]   2> 8279 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8280 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10054 (1416562328229380096)]} 0 5
[junit4:junit4]   2> 8283 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10055 (1416562328237768704)]} 0 1
[junit4:junit4]   2> 8289 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8289 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10055 (1416562328240914432)]} 0 4
[junit4:junit4]   2> 8292 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10056 (1416562328247205888)]} 0 0
[junit4:junit4]   2> 8292 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8293 T735 C31 P39664 /update {wt=javabin&version=2} {add=[53 (1416562328214700032)]} 0 32
[junit4:junit4]   2> 8296 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8296 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10056 (1416562328249303040)]} 0 2
[junit4:junit4]   2> 8300 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10057 (1416562328253497344)]} 0 2
[junit4:junit4]   2> 8322 T719 C32 P43679 /update {wt=javabin&version=2} {add=[54 (1416562328250351616)]} 0 27
[junit4:junit4]   2> 8331 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8331 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10057 (1416562328257691648)]} 0 30
[junit4:junit4]   2> 8334 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10058 (1416562328290197504)]} 0 1
[junit4:junit4]   2> 8338 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8339 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10058 (1416562328293343232)]} 0 3
[junit4:junit4]   2> 8340 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10057 (-1416562328297537536)]} 0 0
[junit4:junit4]   2> 8344 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8344 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10057 (-1416562328299634688)]} 0 2
[junit4:junit4]   2> 8346 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10059 (1416562328303828992)]} 0 0
[junit4:junit4]   2> 8348 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=22 
[junit4:junit4]   2> 8349 T735 C31 P39664 /update {wt=javabin&version=2} {add=[54 (1416562328280760320)]} 0 25
[junit4:junit4]   2> 8351 T719 C32 P43679 /update {wt=javabin&version=2} {add=[55 (1416562328308023296)]} 0 1
[junit4:junit4]   2> 8351 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8351 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10059 (1416562328306974720)]} 0 2
[junit4:junit4]   2> 8353 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10060 (1416562328311169024)]} 0 0
[junit4:junit4]   2> 8355 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8355 T735 C31 P39664 /update {wt=javabin&version=2} {add=[55 (1416562328310120448)]} 0 3
[junit4:junit4]   2> 8357 T719 C32 P43679 /update {wt=javabin&version=2} {add=[56 (1416562328315363328)]} 0 0
[junit4:junit4]   2> 8358 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8358 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10060 (1416562328313266176)]} 0 3
[junit4:junit4]   2> 8360 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10061 (1416562328318509056)]} 0 0
[junit4:junit4]   2> 8361 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8362 T735 C31 P39664 /update {wt=javabin&version=2} {add=[56 (1416562328317460480)]} 0 3
[junit4:junit4]   2> 8364 T719 C32 P43679 /update {wt=javabin&version=2} {add=[57 (1416562328321654784)]} 0 1
[junit4:junit4]   2> 8364 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8364 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10061 (1416562328320606208)]} 0 2
[junit4:junit4]   2> 8367 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10062 (1416562328324800512)]} 0 0
[junit4:junit4]   2> 8367 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8368 T735 C31 P39664 /update {wt=javabin&version=2} {add=[57 (1416562328323751936)]} 0 3
[junit4:junit4]   2> 8370 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8371 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10062 (1416562328326897664)]} 0 3
[junit4:junit4]   2> 8373 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10060 (-1416562328332140544)]} 0 1
[junit4:junit4]   2> 8376 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8377 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10060 (-1416562328334237696)]} 0 2
[junit4:junit4]   2> 8380 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10063 (1416562328337383424)]} 0 2
[junit4:junit4]   2> 8399 T719 C32 P43679 /update {wt=javabin&version=2} {add=[58 (1416562328327946240)]} 0 30
[junit4:junit4]   2> 8404 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8404 T735 C31 P39664 /update {wt=javabin&version=2} {add=[58 (1416562328361500672)]} 0 3
[junit4:junit4]   2> 8406 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[57 (-1416562328366743552)]} 0 0
[junit4:junit4]   2> 8409 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8410 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[57 (-1416562328368840704)]} 0 2
[junit4:junit4]   2> 8412 T719 C32 P43679 /update {wt=javabin&version=2} {add=[59 (1416562328373035008)]} 0 1
[junit4:junit4]   2> 8413 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=30 
[junit4:junit4]   2> 8413 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10063 (1416562328341577728)]} 0 31
[junit4:junit4]   2> 8416 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10064 (1416562328376180736)]} 0 1
[junit4:junit4]   2> 8416 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8416 T735 C31 P39664 /update {wt=javabin&version=2} {add=[59 (1416562328375132160)]} 0 2
[junit4:junit4]   2> 8418 T719 C32 P43679 /update {wt=javabin&version=2} {add=[60 (1416562328379326464)]} 0 0
[junit4:junit4]   2> 8420 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8420 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10064 (1416562328378277888)]} 0 3
[junit4:junit4]   2> 8422 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10065 (1416562328383520768)]} 0 0
[junit4:junit4]   2> 8422 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8423 T735 C31 P39664 /update {wt=javabin&version=2} {add=[60 (1416562328381423616)]} 0 3
[junit4:junit4]   2> 8425 T719 C32 P43679 /update {wt=javabin&version=2} {add=[61 (1416562328386666496)]} 0 1
[junit4:junit4]   2> 8426 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8427 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10065 (1416562328385617920)]} 0 2
[junit4:junit4]   2> 8429 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10066 (1416562328389812224)]} 0 1
[junit4:junit4]   2> 8429 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8430 T735 C31 P39664 /update {wt=javabin&version=2} {add=[61 (1416562328388763648)]} 0 4
[junit4:junit4]   2> 8431 T719 C32 P43679 /update {wt=javabin&version=2} {add=[62 (1416562328392957952)]} 0 0
[junit4:junit4]   2> 8433 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8433 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10066 (1416562328392957952)]} 0 2
[junit4:junit4]   2> 8435 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10062 (-1416562328397152256)]} 0 0
[junit4:junit4]   2> 8436 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8436 T735 C31 P39664 /update {wt=javabin&version=2} {add=[62 (1416562328395055104)]} 0 3
[junit4:junit4]   2> 8438 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[60 (-1416562328400297984)]} 0 0
[junit4:junit4]   2> 8438 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8439 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10062 (-1416562328399249408)]} 0 2
[junit4:junit4]   2> 8441 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10067 (1416562328403443712)]} 0 0
[junit4:junit4]   2> 8441 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8442 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[60 (-1416562328402395136)]} 0 2
[junit4:junit4]   2> 8484 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8484 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10067 (1416562328405540864)]} 0 41
[junit4:junit4]   2> 8486 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10063 (-1416562328450629632)]} 0 0
[junit4:junit4]   2> 8490 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8490 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10063 (-1416562328452726784)]} 0 2
[junit4:junit4]   2> 8490 T719 C32 P43679 /update {wt=javabin&version=2} {add=[63 (1416562328406589440)]} 0 46
[junit4:junit4]   2> 8493 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10068 (1416562328456921088)]} 0 1
[junit4:junit4]   2> 8540 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=46 
[junit4:junit4]   2> 8541 T736 C31 P39664 /update {wt=javabin&version=2} {add=[63 (1416562328456921088)]} 0 49
[junit4:junit4]   2> 8543 T720 C32 P43679 /update {wt=javabin&version=2} {add=[64 (1416562328510398464)]} 0 0
[junit4:junit4]   2> 8544 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 8544 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10068 (1416562328460066816)]} 0 49
[junit4:junit4]   2> 8547 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10069 (1416562328513544192)]} 0 1
[junit4:junit4]   2> 8548 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8548 T736 C31 P39664 /update {wt=javabin&version=2} {add=[64 (1416562328512495616)]} 0 3
[junit4:junit4]   2> 8550 T720 C32 P43679 /update {wt=javabin&version=2} {add=[65 (1416562328517738496)]} 0 0
[junit4:junit4]   2> 8552 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8552 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10069 (1416562328516689920)]} 0 3
[junit4:junit4]   2> 8554 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10064 (-1416562328521932800)]} 0 0
[junit4:junit4]   2> 8554 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8555 T736 C31 P39664 /update {wt=javabin&version=2} {add=[65 (1416562328519835648)]} 0 3
[junit4:junit4]   2> 8557 T720 C32 P43679 /update {wt=javabin&version=2} {add=[66 (1416562328524029952)]} 0 1
[junit4:junit4]   2> 8558 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8558 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10064 (-1416562328524029952)]} 0 2
[junit4:junit4]   2> 8560 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10070 (1416562328528224256)]} 0 0
[junit4:junit4]   2> 8561 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8561 T736 C31 P39664 /update {wt=javabin&version=2} {add=[66 (1416562328527175680)]} 0 2
[junit4:junit4]   2> 8563 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[62 (-1416562328531369984)]} 0 0
[junit4:junit4]   2> 8565 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8565 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10070 (1416562328530321408)]} 0 3
[junit4:junit4]   2> 8566 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8567 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[62 (-1416562328533467136)]} 0 2
[junit4:junit4]   2> 8567 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10071 (1416562328535564288)]} 0 0
[junit4:junit4]   2> 8569 T719 C32 P43679 /update {wt=javabin&version=2} {add=[67 (1416562328537661440)]} 0 0
[junit4:junit4]   2> 8571 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8572 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10071 (1416562328537661440)]} 0 3
[junit4:junit4]   2> 8573 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8574 T735 C31 P39664 /update {wt=javabin&version=2} {add=[67 (1416562328539758592)]} 0 3
[junit4:junit4]   2> 8574 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10065 (-1416562328542904320)]} 0 0
[junit4:junit4]   2> 8576 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[63 (-1416562328543952896)]} 0 0
[junit4:junit4]   2> 8577 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8583 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10065 (-1416562328545001472)]} 0 7
[junit4:junit4]   2> 8584 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8584 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[63 (-1416562328551292928)]} 0 7
[junit4:junit4]   2> 8585 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10072 (1416562328554438656)]} 0 0
[junit4:junit4]   2> 8589 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8590 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10072 (1416562328556535808)]} 0 3
[junit4:junit4]   2> 8592 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10067 (-1416562328561778688)]} 0 0
[junit4:junit4]   2> 8595 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8596 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10067 (-1416562328563875840)]} 0 2
[junit4:junit4]   2> 8599 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10073 (1416562328568070144)]} 0 1
[junit4:junit4]   2> 8638 T719 C32 P43679 /update {wt=javabin&version=2} {add=[68 (1416562328555487232)]} 0 52
[junit4:junit4]   2> 8643 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8643 T735 C31 P39664 /update {wt=javabin&version=2} {add=[68 (1416562328612110336)]} 0 3
[junit4:junit4]   2> 8646 T719 C32 P43679 /update {wt=javabin&version=2} {add=[69 (1416562328617353216)]} 0 1
[junit4:junit4]   2> 8650 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8650 T735 C31 P39664 /update {wt=javabin&version=2} {add=[69 (1416562328619450368)]} 0 3
[junit4:junit4]   2> 8652 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[64 (-1416562328624693248)]} 0 0
[junit4:junit4]   2> 8654 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=51 
[junit4:junit4]   2> 8654 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10073 (1416562328571215872)]} 0 53
[junit4:junit4]   2> 8656 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8656 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[64 (-1416562328626790400)]} 0 2
[junit4:junit4]   2> 8656 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10068 (-1416562328628887552)]} 0 0
[junit4:junit4]   2> 8658 T720 C32 P43679 /update {wt=javabin&version=2} {add=[70 (1416562328630984704)]} 0 0
[junit4:junit4]   2> 8660 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8660 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10068 (-1416562328630984704)]} 0 2
[junit4:junit4]   2> 8662 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8662 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10074 (1416562328635179008)]} 0 0
[junit4:junit4]   2> 8663 T736 C31 P39664 /update {wt=javabin&version=2} {add=[70 (1416562328633081856)]} 0 3
[junit4:junit4]   2> 8665 T720 C32 P43679 /update {wt=javabin&version=2} {add=[71 (1416562328638324736)]} 0 0
[junit4:junit4]   2> 8667 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8667 T735 C31 P39664 /update {wt=javabin&version=2} {add=[10074 (1416562328638324736)]} 0 3
[junit4:junit4]   2> 8669 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8669 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10070 (-1416562328642519040)]} 0 0
[junit4:junit4]   2> 8669 T736 C31 P39664 /update {wt=javabin&version=2} {add=[71 (1416562328640421888)]} 0 2
[junit4:junit4]   2> 8671 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[65 (-1416562328644616192)]} 0 0
[junit4:junit4]   2> 8672 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8673 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[10070 (-1416562328644616192)]} 0 2
[junit4:junit4]   2> 8674 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8675 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[65 (-1416562328646713344)]} 0 2
[junit4:junit4]   2> 8675 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10075 (1416562328648810496)]} 0 0
[junit4:junit4]   2> 8677 T719 C32 P43679 /update {wt=javabin&version=2} {add=[72 (1416562328650907648)]} 0 0
[junit4:junit4]   2> 8681 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8681 T735 C31 P39664 /update {wt=javabin&version=2} {add=[72 (1416562328653004800)]} 0 2
[junit4:junit4]   2> 8683 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[67 (-1416562328657199104)]} 0 0
[junit4:junit4]   2> 8686 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8687 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[67 (-1416562328659296256)]} 0 2
[junit4:junit4]   2> 8689 T719 C32 P43679 /update {wt=javabin&version=2} {add=[73 (1416562328663490560)]} 0 0
[junit4:junit4]   2> 8693 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8693 T735 C31 P39664 /update {wt=javabin&version=2} {add=[73 (1416562328665587712)]} 0 2
[junit4:junit4]   2> 8695 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[68 (-1416562328669782016)]} 0 0
[junit4:junit4]   2> 8698 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8699 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[68 (-1416562328671879168)]} 0 2
[junit4:junit4]   2> 8701 T719 C32 P43679 /update {wt=javabin&version=2} {add=[74 (1416562328676073472)]} 0 1
[junit4:junit4]   2> 8705 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8705 T735 C31 P39664 /update {wt=javabin&version=2} {add=[74 (1416562328678170624)]} 0 2
[junit4:junit4]   2> 8707 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[70 (-1416562328682364928)]} 0 0
[junit4:junit4]   2> 8711 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8711 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[70 (-1416562328684462080)]} 0 2
[junit4:junit4]   2> 8713 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8714 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10075 (1416562328650907648)]} 0 37
[junit4:junit4]   2> 8715 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10071 (-1416562328690753536)]} 0 0
[junit4:junit4]   2> 8719 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8719 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10071 (-1416562328692850688)]} 0 2
[junit4:junit4]   2> 8722 T720 C32 P43679 /update {wt=javabin&version=2} {add=[10076 (1416562328697044992)]} 0 1
[junit4:junit4]   2> 9087 T719 C32 P43679 /update {wt=javabin&version=2} {add=[75 (1416562328688656384)]} 0 374
[junit4:junit4]   2> 9197 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=471 
[junit4:junit4]   2> 9198 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10076 (1416562328700190720)]} 0 474
[junit4:junit4]   2> 9200 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10072 (-1416562329199312896)]} 0 0
[junit4:junit4]   2> 9203 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9204 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10072 (-1416562329201410048)]} 0 2
[junit4:junit4]   2> 9206 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10077 (1416562329205604352)]} 0 0
[junit4:junit4]   2> 9212 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9212 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10077 (1416562329207701504)]} 0 4
[junit4:junit4]   2> 9214 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10073 (-1416562329213992960)]} 0 0
[junit4:junit4]   2> 9218 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9218 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10073 (-1416562329216090112)]} 0 2
[junit4:junit4]   2> 9221 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10078 (1416562329220284416)]} 0 1
[junit4:junit4]   2> 9225 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9226 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10078 (1416562329223430144)]} 0 3
[junit4:junit4]   2> 9228 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10074 (-1416562329228673024)]} 0 0
[junit4:junit4]   2> 9231 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9232 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10074 (-1416562329230770176)]} 0 2
[junit4:junit4]   2> 9235 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10079 (1416562329234964480)]} 0 1
[junit4:junit4]   2> 9239 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9240 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10079 (1416562329237061632)]} 0 4
[junit4:junit4]   2> 9243 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10075 (-1416562329242304512)]} 0 2
[junit4:junit4]   2> 9246 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9247 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10075 (-1416562329246498816)]} 0 2
[junit4:junit4]   2> 9249 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10080 (1416562329250693120)]} 0 1
[junit4:junit4]   2> 9253 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9254 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10080 (1416562329252790272)]} 0 3
[junit4:junit4]   2> 9256 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10081 (1416562329258033152)]} 0 0
[junit4:junit4]   2> 9260 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9261 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10081 (1416562329260130304)]} 0 2
[junit4:junit4]   2> 9262 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10076 (-1416562329264324608)]} 0 0
[junit4:junit4]   2> 9265 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9266 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10076 (-1416562329266421760)]} 0 2
[junit4:junit4]   2> 9268 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10082 (1416562329270616064)]} 0 0
[junit4:junit4]   2> 9272 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9272 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10082 (1416562329272713216)]} 0 2
[junit4:junit4]   2> 9274 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10077 (-1416562329276907520)]} 0 0
[junit4:junit4]   2> 9277 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9278 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10077 (-1416562329279004672)]} 0 2
[junit4:junit4]   2> 9280 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10083 (1416562329282150400)]} 0 1
[junit4:junit4]   2> 9284 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9284 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10083 (1416562329284247552)]} 0 3
[junit4:junit4]   2> 9286 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10080 (-1416562329289490432)]} 0 0
[junit4:junit4]   2> 9289 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9289 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10080 (-1416562329290539008)]} 0 2
[junit4:junit4]   2> 9291 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10084 (1416562329294733312)]} 0 0
[junit4:junit4]   2> 9295 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9296 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10084 (1416562329296830464)]} 0 3
[junit4:junit4]   2> 9315 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10085 (1416562329301024768)]} 0 18
[junit4:junit4]   2> 9400 T755 C30 P37063 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 9400 T755 C30 P37063 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=./org.apache.solr.cloud.RecoveryZkTest-1350939101768/jetty2/tlog/tlog.0000000000000000000 refcount=1}}
[junit4:junit4]   2> 9400 T755 C30 P37063 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:39664/solr/collection1/. core=collection1
[junit4:junit4]   2> 9401 T755 C30 P37063 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9404 T737 C31 P39664 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9427 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9428 T735 C31 P39664 /update {wt=javabin&version=2} {add=[75 (1416562329082920960)]} 0 339
[junit4:junit4]   2> 9430 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[71 (-1416562329440485376)]} 0 0
[junit4:junit4]   2> 9433 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9434 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[71 (-1416562329442582528)]} 0 3
[junit4:junit4]   2> 9436 T719 C32 P43679 /update {wt=javabin&version=2} {add=[76 (1416562329445728256)]} 0 1
[junit4:junit4]   2> 9440 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9441 T735 C31 P39664 /update {wt=javabin&version=2} {add=[76 (1416562329448873984)]} 0 3
[junit4:junit4]   2> 9443 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[72 (-1416562329454116864)]} 0 1
[junit4:junit4]   2> 9447 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9448 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[72 (-1416562329455165440)]} 0 4
[junit4:junit4]   2> 9451 T719 C32 P43679 /update {wt=javabin&version=2} {add=[77 (1416562329461456896)]} 0 1
[junit4:junit4]   2> 9455 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9455 T735 C31 P39664 /update {wt=javabin&version=2} {add=[77 (1416562329464602624)]} 0 3
[junit4:junit4]   2> 9457 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[73 (-1416562329468796928)]} 0 0
[junit4:junit4]   2> 9460 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9461 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[73 (-1416562329470894080)]} 0 2
[junit4:junit4]   2> 9463 T719 C32 P43679 /update {wt=javabin&version=2} {add=[78 (1416562329475088384)]} 0 0
[junit4:junit4]   2> 9467 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9467 T735 C31 P39664 /update {wt=javabin&version=2} {add=[78 (1416562329477185536)]} 0 2
[junit4:junit4]   2> 9469 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[74 (-1416562329481379840)]} 0 0
[junit4:junit4]   2> 9473 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9473 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[74 (-1416562329483476992)]} 0 2
[junit4:junit4]   2> 9476 T719 C32 P43679 /update {wt=javabin&version=2} {add=[79 (1416562329487671296)]} 0 1
[junit4:junit4]   2> 9480 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9480 T735 C31 P39664 /update {wt=javabin&version=2} {add=[79 (1416562329490817024)]} 0 2
[junit4:junit4]   2> 9482 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[75 (-1416562329495011328)]} 0 0
[junit4:junit4]   2> 9486 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9486 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[75 (-1416562329497108480)]} 0 2
[junit4:junit4]   2> 9489 T719 C32 P43679 /update {wt=javabin&version=2} {add=[80 (1416562329501302784)]} 0 1
[junit4:junit4]   2> 9493 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9494 T735 C31 P39664 /update {wt=javabin&version=2} {add=[80 (1416562329504448512)]} 0 3
[junit4:junit4]   2> 9496 T719 C32 P43679 /update {wt=javabin&version=2} {add=[81 (1416562329508642816)]} 0 1
[junit4:junit4]   2> 9500 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9501 T735 C31 P39664 /update {wt=javabin&version=2} {add=[81 (1416562329511788544)]} 0 3
[junit4:junit4]   2> 9502 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[76 (-1416562329515982848)]} 0 0
[junit4:junit4]   2> 9506 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9507 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[76 (-1416562329518080000)]} 0 3
[junit4:junit4]   2> 9509 T719 C32 P43679 /update {wt=javabin&version=2} {add=[82 (1416562329522274304)]} 0 1
[junit4:junit4]   2> 9513 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9514 T735 C31 P39664 /update {wt=javabin&version=2} {add=[82 (1416562329525420032)]} 0 3
[junit4:junit4]   2> 9516 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[77 (-1416562329529614336)]} 0 0
[junit4:junit4]   2> 9519 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9519 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[77 (-1416562329531711488)]} 0 2
[junit4:junit4]   2> 9522 T719 C32 P43679 /update {wt=javabin&version=2} {add=[83 (1416562329535905792)]} 0 1
[junit4:junit4]   2> 9526 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9526 T735 C31 P39664 /update {wt=javabin&version=2} {add=[83 (1416562329539051520)]} 0 2
[junit4:junit4]   2> 9528 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[80 (-1416562329543245824)]} 0 0
[junit4:junit4]   2> 9532 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9532 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[80 (-1416562329545342976)]} 0 2
[junit4:junit4]   2> 9534 T719 C32 P43679 /update {wt=javabin&version=2} {add=[84 (1416562329549537280)]} 0 0
[junit4:junit4]   2> 9538 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9539 T735 C31 P39664 /update {wt=javabin&version=2} {add=[84 (1416562329551634432)]} 0 3
[junit4:junit4]   2> 9566 T719 C32 P43679 /update {wt=javabin&version=2} {add=[85 (1416562329555828736)]} 0 26
[junit4:junit4]   2> 9571 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9571 T735 C31 P39664 /update {wt=javabin&version=2} {add=[85 (1416562329585188864)]} 0 3
[junit4:junit4]   2> 9573 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[81 (-1416562329590431744)]} 0 0
[junit4:junit4]   2> 9576 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9577 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[81 (-1416562329592528896)]} 0 2
[junit4:junit4]   2> 9583 T719 C32 P43679 /update {wt=javabin&version=2} {add=[86 (1416562329599868928)]} 0 1
[junit4:junit4]   2> 9587 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9588 T735 C31 P39664 /update {wt=javabin&version=2} {add=[86 (1416562329601966080)]} 0 4
[junit4:junit4]   2> 9590 T719 C32 P43679 /update {wt=javabin&version=2} {add=[87 (1416562329607208960)]} 0 1
[junit4:junit4]   2> 9593 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9594 T735 C31 P39664 /update {wt=javabin&version=2} {add=[87 (1416562329610354688)]} 0 3
[junit4:junit4]   2> 9596 T719 C32 P43679 /update {wt=javabin&version=2} {add=[88 (1416562329614548992)]} 0 0
[junit4:junit4]   2> 9600 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9600 T735 C31 P39664 /update {wt=javabin&version=2} {add=[88 (1416562329616646144)]} 0 2
[junit4:junit4]   2> 9602 T719 C32 P43679 /update {wt=javabin&version=2} {add=[89 (1416562329620840448)]} 0 0
[junit4:junit4]   2> 9606 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9606 T735 C31 P39664 /update {wt=javabin&version=2} {add=[89 (1416562329622937600)]} 0 2
[junit4:junit4]   2> 9608 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[82 (-1416562329627131904)]} 0 0
[junit4:junit4]   2> 9612 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9612 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[82 (-1416562329629229056)]} 0 2
[junit4:junit4]   2> 9615 T719 C32 P43679 /update {wt=javabin&version=2} {add=[90 (1416562329633423360)]} 0 1
[junit4:junit4]   2> 9619 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9619 T735 C31 P39664 /update {wt=javabin&version=2} {add=[90 (1416562329635520512)]} 0 3
[junit4:junit4]   2> 9621 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[84 (-1416562329640763392)]} 0 0
[junit4:junit4]   2> 9624 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9625 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[84 (-1416562329642860544)]} 0 2
[junit4:junit4]   2> 9628 T719 C32 P43679 /update {wt=javabin&version=2} {add=[91 (1416562329648103424)]} 0 0
[junit4:junit4]   2> 9632 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9633 T735 C31 P39664 /update {wt=javabin&version=2} {add=[91 (1416562329650200576)]} 0 3
[junit4:junit4]   2> 9635 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[87 (-1416562329654394880)]} 0 1
[junit4:junit4]   2> 9640 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9640 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9640 T735 C31 P39664 /update {wt=javabin&version=2} {delete=[87 (-1416562329656492032)]} 0 4
[junit4:junit4]   2> 9641 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10085 (1416562329321996288)]} 0 324
[junit4:junit4]   2> 9645 T720 C32 P43679 /update {wt=javabin&version=2} {delete=[10081 (-1416562329665929216)]} 0 1
[junit4:junit4]   2> 9645 T719 C32 P43679 /update {wt=javabin&version=2} {add=[92 (1416562329664880640)]} 0 1
[junit4:junit4]   2> 9648 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9648 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10081 (-1416562329666977792)]} 0 2
[junit4:junit4]   2> 9651 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10086 (1416562329671172096)]} 0 1
[junit4:junit4]   2> 9655 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9655 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10086 (1416562329673269248)]} 0 3
[junit4:junit4]   2> 9657 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10087 (1416562329678512128)]} 0 0
[junit4:junit4]   2> 9661 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9661 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10087 (1416562329680609280)]} 0 2
[junit4:junit4]   2> 9700 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10088 (1416562329684803584)]} 0 37
[junit4:junit4]   2> 9704 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9704 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10088 (1416562329725698048)]} 0 2
[junit4:junit4]   2> 9707 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10089 (1416562329729892352)]} 0 1
[junit4:junit4]   2> 9711 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9711 T736 C31 P39664 /update {wt=javabin&version=2} {add=[10089 (1416562329733038080)]} 0 2
[junit4:junit4]   2> 9713 T719 C32 P43679 /update {wt=javabin&version=2} {delete=[10082 (-1416562329737232384)]} 0 0
[junit4:junit4]   2> 9716 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9717 T736 C31 P39664 /update {wt=javabin&version=2} {delete=[10082 (-1416562329739329536)]} 0 2
[junit4:junit4]   2> 9719 T719 C32 P43679 /update {wt=javabin&version=2} {add=[10090 (1416562329742475264)]} 0 1
[junit4:junit4]   2> 9722 T745 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9722 T735 C31 P39664 /update {wt=javabin&version=2} {add=[92 (1416562329668026368)]} 0 75
[junit4:junit4]   2> 9723 T746 C30 P37063 REQ /update {distrib.from=http://127.0.0.1:39664/solr/collection1/&update.distrib=FROMLEADER&wt=java

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

t4:junit4]   2> 28628 T741 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 28628 T707 oaz.ZooKeeper.close Session: 0x13a8a3eca4b0003 closed
[junit4:junit4]   2> 28629 T707 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 28682 T707 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 37063
[junit4:junit4]   2> 28682 T707 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=454277080
[junit4:junit4]   2> 29606 T813 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41986
[junit4:junit4]   2> 29707 T814 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 29707 T707 oaz.ZooKeeper.close Session: 0x13a8a3eca4b0007 closed
[junit4:junit4]   2> 29709 T707 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 29821 T707 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=85612DDEDD4AF775 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_LY -Dtests.timezone=SystemV/MST7 -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   29.9s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:37063/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([85612DDEDD4AF775:487A3C6AA159749]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:738)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:774)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:683)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:44)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:56)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   2> 29921 T707 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {range_facet_l=PostingsFormat(name=Lucene41WithOrds), text=PostingsFormat(name=MockSep), _version_=PostingsFormat(name=Lucene41WithOrds), rnd_b=Pulsing41(freqCutoff=17 minBlockSize=74 maxBlockSize=225), intDefault=PostingsFormat(name=Lucene41WithOrds), id=Pulsing41(freqCutoff=17 minBlockSize=74 maxBlockSize=225), timestamp=PostingsFormat(name=Lucene41WithOrds), a_t=PostingsFormat(name=Lucene41WithOrds), range_facet_sl=PostingsFormat(name=MockSep), range_facet_si=PostingsFormat(name=SimpleText), other_tl1=PostingsFormat(name=Lucene41WithOrds), multiDefault=PostingsFormat(name=SimpleText), a_si=PostingsFormat(name=SimpleText)}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ar_LY, timezone=SystemV/MST7
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic i386/Oracle Corporation 1.6.0_33 (32-bit)/cpus=8,threads=1,free=176988736,total=346308608
[junit4:junit4]   2> NOTE: All tests run in this JVM: [HighlighterTest, SolrCoreTest, SuggesterTSTTest, OverseerTest, XmlUpdateRequestHandlerTest, IndexSchemaRuntimeFieldTest, FullSolrCloudDistribCmdsTest, TestOmitPositions, TestValueSourceCache, ShowFileRequestHandlerTest, StatsComponentTest, SolrInfoMBeanTest, IndexReaderFactoryTest, LegacyHTMLStripCharFilterTest, SpellingQueryConverterTest, BadIndexSchemaTest, DebugComponentTest, TestLRUCache, TestDocSet, TestBM25SimilarityFactory, ZkCLITest, DocumentBuilderTest, TestFunctionQuery, TestArbitraryIndexDir, PrimitiveFieldTypeTest, UUIDFieldTest, DOMUtilTest, TestBinaryField, TestDocumentBuilder, LukeRequestHandlerTest, TestQuerySenderListener, DocumentAnalysisRequestHandlerTest, TestFoldingMultitermQuery, TestPropInjectDefaults, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 29.99s, 1 test, 1 error <<< FAILURES!

[...truncated 556 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:1170: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:838: There were test failures: 232 suites, 960 tests, 1 error, 7 ignored (2 assumptions)

Total time: 33 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jrockit-jdk1.6.0_33-R28.2.4-4.1.0 -XnoOpt
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message