lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b51) - Build # 859 - Failure!
Date Tue, 04 Sep 2012 04:04:14 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/859/
Java: 32bit/jdk1.8.0-ea-b51 -client -XX:+UseConcMarkSweepGC

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

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://localhost:57288/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([C16BF03659D8C09:8DF0311B12C2EC35]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:769)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:429)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 6791 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 2 T590 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-1346730985482
[junit4:junit4]   2> 3 T590 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T591 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 5 T591 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 6 T591 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 103 T590 oasc.ZkTestServer.run start zk server on port:45903
[junit4:junit4]   2> 105 T590 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:45903 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1636901
[junit4:junit4]   2> 106 T596 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:45903
[junit4:junit4]   2> 106 T590 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 107 T596 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:45903, initiating session
[junit4:junit4]   2> 107 T592 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44911
[junit4:junit4]   2> 107 T592 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44911
[junit4:junit4]   2> 107 T594 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 214 T594 oazs.NIOServerCnxn.finishSessionInit Established session 0x1398f6c080d0000 with negotiated timeout 10000 for client /127.0.0.1:44911
[junit4:junit4]   2> 214 T596 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:45903, sessionid = 0x1398f6c080d0000, negotiated timeout = 10000
[junit4:junit4]   2> 215 T597 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1636901 name:ZooKeeperConnection Watcher:127.0.0.1:45903 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 215 T590 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 215 T590 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 218 T595 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x1398f6c080d0000
[junit4:junit4]   2> 218 T597 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 218 T590 oaz.ZooKeeper.close Session: 0x1398f6c080d0000 closed
[junit4:junit4]   2> 219 T592 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44911 which had sessionid 0x1398f6c080d0000
[junit4:junit4]   2> 219 T590 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:45903/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@8106c0
[junit4:junit4]   2> 225 T598 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:45903
[junit4:junit4]   2> 220 T590 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 226 T592 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44912
[junit4:junit4]   2> 227 T598 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:45903, initiating session
[junit4:junit4]   2> 227 T592 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44912
[junit4:junit4]   2> 229 T594 oazs.NIOServerCnxn.finishSessionInit Established session 0x1398f6c080d0001 with negotiated timeout 10000 for client /127.0.0.1:44912
[junit4:junit4]   2> 229 T598 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:45903, sessionid = 0x1398f6c080d0001, negotiated timeout = 10000
[junit4:junit4]   2> 229 T599 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8106c0 name:ZooKeeperConnection Watcher:127.0.0.1:45903/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 229 T590 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 230 T590 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 232 T590 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 234 T590 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 235 T590 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 237 T590 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> 238 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 241 T590 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> 242 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 244 T590 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> 245 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 247 T590 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> 248 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 250 T590 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> 250 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 252 T590 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> 253 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 254 T590 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> 255 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 257 T590 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> 257 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 259 T590 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> 259 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 261 T590 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> 261 T590 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 264 T595 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x1398f6c080d0001
[junit4:junit4]   2> 264 T599 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 264 T590 oaz.ZooKeeper.close Session: 0x1398f6c080d0001 closed
[junit4:junit4]   2> 265 T592 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x1398f6c080d0001, likely client has closed socket
[junit4:junit4]   2> 265 T592 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44912 which had sessionid 0x1398f6c080d0001
[junit4:junit4]   2> 265 T590 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 267 T590 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:60514
[junit4:junit4]   2> 268 T590 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 268 T590 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 268 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 283 T590 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 283 T590 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 283 T590 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 284 T590 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 284 T590 oasc.CoreContainer.<init> New CoreContainer 15346264
[junit4:junit4]   2> 284 T590 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 285 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 298 T590 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 307 T590 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:45903/solr
[junit4:junit4]   2> 308 T590 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:45903/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@17ad996
[junit4:junit4]   2> 309 T609 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:45903
[junit4:junit4]   2> 309 T590 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 310 T609 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:45903, initiating session
[junit4:junit4]   2> 310 T592 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44913
[junit4:junit4]   2> 310 T592 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44913
[junit4:junit4]   2> 311 T594 oazs.NIOServerCnxn.finishSessionInit Established session 0x1398f6c080d0002 with negotiated timeout 8000 for client /127.0.0.1:44913
[junit4:junit4]   2> 311 T609 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:45903, sessionid = 0x1398f6c080d0002, negotiated timeout = 8000
[junit4:junit4]   2> 311 T610 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17ad996 name:ZooKeeperConnection Watcher:127.0.0.1:45903/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 312 T590 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 313 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 314 T590 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 315 T590 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60514_solr
[junit4:junit4]   2> 316 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60514_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60514_solr
[junit4:junit4]   2> 319 T590 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60514_solr
[junit4:junit4]   2> 321 T590 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 322 T590 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 330 T590 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 331 T590 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 331 T590 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 331 T590 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 332 T590 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 332 T590 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 332 T590 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 333 T590 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 336 T590 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 339 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 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> 340 T590 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 341 T590 oasc.Overseer.start Overseer (id=88259361864744962-127.0.0.1:60514_solr-n_0000000000) starting
[junit4:junit4]   2> 342 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 343 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 343 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 344 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 345 T612 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 345 T590 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 348 T590 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 349 T611 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 353 T590 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 353 T590 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 354 T590 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 354 T590 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 355 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 356 T590 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 356 T590 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 387 T590 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 441 T590 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 442 T590 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 446 T590 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 887 T590 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 892 T590 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 895 T590 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 905 T590 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 908 T590 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 912 T590 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 913 T590 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 916 T590 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1346730985482/control/data/
[junit4:junit4]   2> 917 T590 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c1e458
[junit4:junit4]   2> 924 T590 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1346730985482/control/data/index/
[junit4:junit4]   2> 924 T590 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1346730985482/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 925 T590 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-1346730985482/control/data/index forceNew:false
[junit4:junit4]   2> 928 T590 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1346730985482/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17bd37e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 928 T590 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 929 T590 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 930 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 931 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 931 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 932 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 932 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 932 T590 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 933 T590 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 934 T590 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 934 T590 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 935 T590 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 935 T590 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 936 T590 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 936 T590 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 936 T590 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 937 T590 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 937 T590 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 938 T590 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 938 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 939 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 939 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 940 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 940 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 940 T590 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 941 T590 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 941 T590 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 942 T590 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 944 T590 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 945 T590 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML]
[junit4:junit4]   2> 958 T590 oass.SolrIndexSearcher.<init> Opening Searcher@14ae5a4 main
[junit4:junit4]   2> 959 T590 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 959 T590 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 963 T590 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 968 T590 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 988 T613 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14ae5a4 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 992 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 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> 993 T590 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 994 T590 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60514/solr shardId:control_shard
[junit4:junit4]   2> 994 T590 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1002 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 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> 1002 T590 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 1003 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1004 T590 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60514/solr/collection1/
[junit4:junit4]   2> 1005 T590 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1008 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:create cxid:0x6c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1354 T611 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1355 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0002 type:create cxid:0x74 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1363 T610 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1410 T590 oasc.ZkController.register We are http://127.0.0.1:60514/solr/collection1/ and leader is http://127.0.0.1:60514/solr/collection1/
[junit4:junit4]   2> 1410 T590 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60514/solr
[junit4:junit4]   2> 1411 T590 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1413 T590 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1414 T590 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1414 T590 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1415 T590 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1419 T590 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1421 T590 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:56045
[junit4:junit4]   2> 1421 T590 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1421 T590 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 1422 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 1434 T590 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1434 T590 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1435 T590 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 1435 T590 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 1435 T590 oasc.CoreContainer.<init> New CoreContainer 17094569
[junit4:junit4]   2> 1436 T590 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 1436 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 1448 T590 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1459 T590 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:45903/solr
[junit4:junit4]   2> 1460 T590 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:45903/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1d8ff77
[junit4:junit4]   2> 1463 T623 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:45903
[junit4:junit4]   2> 1463 T590 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1463 T623 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:45903, initiating session
[junit4:junit4]   2> 1463 T592 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44914
[junit4:junit4]   2> 1464 T592 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44914
[junit4:junit4]   2> 1465 T594 oazs.NIOServerCnxn.finishSessionInit Established session 0x1398f6c080d0003 with negotiated timeout 8000 for client /127.0.0.1:44914
[junit4:junit4]   2> 1465 T623 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:45903, sessionid = 0x1398f6c080d0003, negotiated timeout = 8000
[junit4:junit4]   2> 1465 T624 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d8ff77 name:ZooKeeperConnection Watcher:127.0.0.1:45903/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1465 T590 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1466 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1467 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1467 T590 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56045_solr
[junit4:junit4]   2> 1468 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56045_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56045_solr
[junit4:junit4]   2> 1468 T590 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56045_solr
[junit4:junit4]   2> 1470 T610 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 1470 T590 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1471 T590 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1471 T590 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1471 T590 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1472 T590 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1472 T590 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1472 T590 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1473 T590 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1473 T590 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1473 T590 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1478 T590 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1482 T590 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 1483 T590 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 1483 T590 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1484 T590 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 1485 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 1485 T590 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 1486 T590 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1516 T590 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1565 T590 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1566 T590 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1570 T590 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1868 T611 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1872 T624 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1872 T610 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2040 T590 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2046 T590 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2050 T590 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2061 T590 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2064 T590 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2067 T590 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2068 T590 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2069 T590 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty1/
[junit4:junit4]   2> 2069 T590 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c1e458
[junit4:junit4]   2> 2070 T590 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty1/index/
[junit4:junit4]   2> 2071 T590 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2072 T590 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-1346730985482/jetty1/index forceNew:false
[junit4:junit4]   2> 2075 T590 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17552e2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2075 T590 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2076 T590 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2077 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2078 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2078 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2078 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2079 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2079 T590 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2080 T590 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2080 T590 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2081 T590 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2081 T590 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2082 T590 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2082 T590 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2083 T590 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2083 T590 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2084 T590 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2084 T590 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2085 T590 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2085 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2094 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2095 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2095 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2096 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2097 T590 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2097 T590 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2098 T590 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2099 T590 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2101 T590 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2102 T590 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML]
[junit4:junit4]   2> 2107 T590 oass.SolrIndexSearcher.<init> Opening Searcher@b0b7d9 main
[junit4:junit4]   2> 2108 T590 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2108 T590 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2109 T590 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2113 T590 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2143 T625 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b0b7d9 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2375 T611 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2380 T624 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2380 T610 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2647 T590 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 2647 T590 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56045/solr shardId:shard1
[junit4:junit4]   2> 2648 T590 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 2654 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0003 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> 2654 T590 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 2655 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2656 T590 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56045/solr/collection1/
[junit4:junit4]   2> 2656 T590 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 2659 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2882 T611 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2889 T624 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2889 T610 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2911 T590 oasc.ZkController.register We are http://127.0.0.1:56045/solr/collection1/ and leader is http://127.0.0.1:56045/solr/collection1/
[junit4:junit4]   2> 2911 T590 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56045/solr
[junit4:junit4]   2> 2911 T590 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2913 T590 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2914 T590 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 2914 T590 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2915 T590 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2918 T590 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2922 T590 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:57288
[junit4:junit4]   2> 2923 T590 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2923 T590 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 2924 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2943 T590 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2944 T590 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2944 T590 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 2945 T590 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 2945 T590 oasc.CoreContainer.<init> New CoreContainer 11687599
[junit4:junit4]   2> 2946 T590 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2946 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2965 T590 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2979 T590 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:45903/solr
[junit4:junit4]   2> 2979 T590 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:45903/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@11abc56
[junit4:junit4]   2> 2980 T635 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:45903
[junit4:junit4]   2> 2980 T590 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2981 T635 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:45903, initiating session
[junit4:junit4]   2> 2981 T592 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44915
[junit4:junit4]   2> 2982 T592 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44915
[junit4:junit4]   2> 2982 T594 oazs.NIOServerCnxn.finishSessionInit Established session 0x1398f6c080d0004 with negotiated timeout 8000 for client /127.0.0.1:44915
[junit4:junit4]   2> 2982 T635 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:45903, sessionid = 0x1398f6c080d0004, negotiated timeout = 8000
[junit4:junit4]   2> 2983 T636 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11abc56 name:ZooKeeperConnection Watcher:127.0.0.1:45903/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2983 T590 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2984 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2984 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2985 T590 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57288_solr
[junit4:junit4]   2> 2986 T595 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1398f6c080d0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:57288_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:57288_solr
[junit4:junit4]   2> 2986 T590 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57288_solr
[junit4:junit4]   2> 2988 T610 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2988 T624 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2992 T590 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2992 T590 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2992 T590 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2993 T590 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2993 T590 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2993 T590 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2994 T590 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2994 T590 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2994 T590 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2995 T590 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3004 T590 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3009 T590 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 3009 T590 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3010 T590 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3010 T590 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3011 T590 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 3012 T590 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 3012 T590 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3051 T590 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3101 T590 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3101 T590 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3106 T590 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3394 T611 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3402 T624 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3402 T636 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3402 T610 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3547 T590 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3553 T590 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3556 T590 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3565 T590 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3568 T590 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3571 T590 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3572 T590 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3573 T590 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty2/
[junit4:junit4]   2> 3581 T590 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c1e458
[junit4:junit4]   2> 3581 T590 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty2/index/
[junit4:junit4]   2> 3582 T590 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3582 T590 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-1346730985482/jetty2/index forceNew:false
[junit4:junit4]   2> 3586 T590 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@86376f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3586 T590 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3586 T590 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3588 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3588 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3589 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3589 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3590 T590 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3590 T590 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3590 T590 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3591 T590 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3591 T590 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3592 T590 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3593 T590 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3593 T590 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3594 T590 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3594 T590 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3594 T590 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3595 T590 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3595 T590 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3596 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3596 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3597 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3597 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3598 T590 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3599 T590 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3599 T590 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3600 T590 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3601 T590 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3603 T590 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3604 T590 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML]
[junit4:junit4]   2> 3609 T590 oass.SolrIndexSearcher.<init> Opening Searcher@f8226e main
[junit4:junit4]   2> 3609 T590 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3610 T590 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3610 T590 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3614 T590 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3640 T637 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f8226e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3905 T611 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3909 T610 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3909 T624 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3909 T636 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4144 T590 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4144 T590 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:57288/solr shardId:shard1
[junit4:junit4]   2> 4148 T590 oasc.ZkController.register We are http://127.0.0.1:57288/solr/collection1/ and leader is http://127.0.0.1:56045/solr/collection1/
[junit4:junit4]   2> 4148 T590 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:57288/solr
[junit4:junit4]   2> 4149 T590 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 4149 T590 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C76 name=collection1 org.apache.solr.core.SolrCore@b0737e url=http://127.0.0.1:57288/solr/collection1 node=127.0.0.1:57288_solr
[junit4:junit4]   2> 4154 T638 C76 P57288 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 4154 T590 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4155 T638 C76 P57288 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 4155 T590 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4156 T638 C76 P57288 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4156 T590 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4157 T590 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4162 T590 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:45903/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@67ae5f
[junit4:junit4]   2> 4163 T639 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:45903
[junit4:junit4]   2> 4163 T590 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4163 T639 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:45903, initiating session
[junit4:junit4]   2> 4163 T592 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44917
[junit4:junit4]   2> 4164 T592 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44917
[junit4:junit4]   2> 4165 T594 oazs.NIOServerCnxn.finishSessionInit Established session 0x1398f6c080d0005 with negotiated timeout 10000 for client /127.0.0.1:44917
[junit4:junit4]   2> 4168 T639 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:45903, sessionid = 0x1398f6c080d0005, negotiated timeout = 10000
[junit4:junit4]   2> 4168 T640 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@67ae5f name:ZooKeeperConnection Watcher:127.0.0.1:45903/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4168 T590 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4169 T590 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4171 T590 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4172 T590 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 4175 T590 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C77 name=collection1 org.apache.solr.core.SolrCore@1766b86 url=http://127.0.0.1:60514/solr/collection1 node=127.0.0.1:60514_solr
[junit4:junit4]   2> 4185 T603 C77 P60514 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1346730985482/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17bd37e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4186 T603 C77 P60514 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4190 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10001 (1412149794215821313)]} 0 7
[junit4:junit4]   2> 4190 T603 C77 P60514 /update {wt=javabin&version=2} {add=[1 (1412149794215821312)]} 0 7
[junit4:junit4]   2> 4190 T642 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:45903/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1e5a09b
[junit4:junit4]   2> 4191 T643 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:45903
[junit4:junit4]   2> 4191 T642 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4192 T643 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:45903, initiating session
[junit4:junit4]   2> 4192 T592 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:44921
[junit4:junit4]   2> 4192 T592 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:44921
[junit4:junit4]   2> 4193 T594 oazs.NIOServerCnxn.finishSessionInit Established session 0x1398f6c080d0006 with negotiated timeout 10000 for client /127.0.0.1:44921
[junit4:junit4]   2> 4193 T643 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:45903, sessionid = 0x1398f6c080d0006, negotiated timeout = 10000
[junit4:junit4]   2> 4193 T644 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e5a09b name:ZooKeeperConnection Watcher:127.0.0.1:45903/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4193 T642 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4194 T642 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C78 name=collection1 org.apache.solr.core.SolrCore@148659c url=http://127.0.0.1:56045/solr/collection1 node=127.0.0.1:56045_solr
[junit4:junit4]   2> 4204 T617 C78 P56045 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17552e2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4205 T617 C78 P56045 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4207 T617 C78 P56045 /update {wt=javabin&version=2} {add=[1 (1412149794236792832)]} 0 5
[junit4:junit4]   2> 4208 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10001 (1412149794236792833)]} 0 6
[junit4:junit4]   2> 4211 T603 C77 P60514 /update {wt=javabin&version=2} {add=[2 (1412149794244132864)]} 0 1
[junit4:junit4]   2> 4211 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10002 (1412149794244132865)]} 0 1
[junit4:junit4]   2> 4214 T618 C78 P56045 /update {wt=javabin&version=2} {add=[2 (1412149794247278592)]} 0 1
[junit4:junit4]   2> 4214 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10002 (1412149794247278593)]} 0 1
[junit4:junit4]   2> 4216 T602 C77 P60514 /update {wt=javabin&version=2} {add=[3 (1412149794250424320)]} 0 0
[junit4:junit4]   2> 4217 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10003 (1412149794251472896)]} 0 1
[junit4:junit4]   2> 4219 T617 C78 P56045 /update {wt=javabin&version=2} {add=[3 (1412149794252521472)]} 0 1
[junit4:junit4]   2> 4221 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10003 (1412149794255667200)]} 0 1
[junit4:junit4]   2> 4223 T603 C77 P60514 /update {wt=javabin&version=2} {add=[4 (1412149794257764352)]} 0 1
[junit4:junit4]   2> 4224 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10004 (1412149794258812928)]} 0 1
[junit4:junit4]   2> 4226 T618 C78 P56045 /update {wt=javabin&version=2} {add=[4 (1412149794259861504)]} 0 1
[junit4:junit4]   2> 4227 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10004 (1412149794260910080)]} 0 1
[junit4:junit4]   2> 4238 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[1 (-1412149794272444416)]} 0 1
[junit4:junit4]   2> 4238 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10001 (-1412149794273492992)]} 0 1
[junit4:junit4]   2> 4240 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[1 (-1412149794275590144)]} 0 0
[junit4:junit4]   2> 4240 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10001 (-1412149794275590145)]} 0 0
[junit4:junit4]   2> 4243 T603 C77 P60514 /update {wt=javabin&version=2} {add=[5 (1412149794277687296)]} 0 1
[junit4:junit4]   2> 4243 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10005 (1412149794278735872)]} 0 0
[junit4:junit4]   2> 4246 T618 C78 P56045 /update {wt=javabin&version=2} {add=[5 (1412149794281881600)]} 0 1
[junit4:junit4]   2> 4250 T602 C77 P60514 /update {wt=javabin&version=2} {add=[6 (1412149794285027328)]} 0 1
[junit4:junit4]   2> 4251 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10005 (1412149794281881601)]} 0 5
[junit4:junit4]   2> 4253 T618 C78 P56045 /update {wt=javabin&version=2} {add=[6 (1412149794288173056)]} 0 1
[junit4:junit4]   2> 4254 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10006 (1412149794290270208)]} 0 0
[junit4:junit4]   2> 4256 T603 C77 P60514 /update {wt=javabin&version=2} {add=[7 (1412149794291318784)]} 0 1
[junit4:junit4]   2> 4256 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10006 (1412149794292367360)]} 0 0
[junit4:junit4]   2> 4259 T617 C78 P56045 /update {wt=javabin&version=2} {add=[7 (1412149794294464512)]} 0 1
[junit4:junit4]   2> 4262 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[4 (-1412149794298658816)]} 0 0
[junit4:junit4]   2> 4263 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10007 (1412149794298658817)]} 0 1
[junit4:junit4]   2> 4265 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[4 (-1412149794300755968)]} 0 1
[junit4:junit4]   2> 4266 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10007 (1412149794301804544)]} 0 1
[junit4:junit4]   2> 4267 T603 C77 P60514 /update {wt=javabin&version=2} {add=[8 (1412149794303901696)]} 0 0
[junit4:junit4]   2> 4268 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10004 (-1412149794303901697)]} 0 1
[junit4:junit4]   2> 4270 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10004 (-1412149794307047424)]} 0 1
[junit4:junit4]   2> 4270 T618 C78 P56045 /update {wt=javabin&version=2} {add=[8 (1412149794305998848)]} 0 1
[junit4:junit4]   2> 4272 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10008 (1412149794309144576)]} 0 0
[junit4:junit4]   2> 4272 T603 C77 P60514 /update {wt=javabin&version=2} {add=[9 (1412149794309144577)]} 0 0
[junit4:junit4]   2> 4275 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10008 (1412149794311241728)]} 0 1
[junit4:junit4]   2> 4275 T617 C78 P56045 /update {wt=javabin&version=2} {add=[9 (1412149794311241729)]} 0 1
[junit4:junit4]   2> 4277 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[6 (-1412149794314387457)]} 0 0
[junit4:junit4]   2> 4279 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[6 (-1412149794316484608)]} 0 0
[junit4:junit4]   2> 4281 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10009 (1412149794314387456)]} 0 5
[junit4:junit4]   2> 4283 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10009 (1412149794320678912)]} 0 0
[junit4:junit4]   2> 4284 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10 (1412149794320678912)]} 0 1
[junit4:junit4]   2> 4285 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10006 (-1412149794322776064)]} 0 0
[junit4:junit4]   2> 4286 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10 (1412149794323824640)]} 0 0
[junit4:junit4]   2> 4287 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10006 (-1412149794324873216)]} 0 0
[junit4:junit4]   2> 4289 T603 C77 P60514 /update {wt=javabin&version=2} {add=[11 (1412149794325921792)]} 0 1
[junit4:junit4]   2> 4294 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10010 (1412149794326970368)]} 0 5
[junit4:junit4]   2> 4296 T618 C78 P56045 /update {wt=javabin&version=2} {add=[11 (1412149794329067520)]} 0 5
[junit4:junit4]   2> 4297 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10010 (1412149794334310400)]} 0 1
[junit4:junit4]   2> 4299 T602 C77 P60514 /update {wt=javabin&version=2} {add=[12 (1412149794336407552)]} 0 1
[junit4:junit4]   2> 4300 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10011 (1412149794337456128)]} 0 1
[junit4:junit4]   2> 4301 T617 C78 P56045 /update {wt=javabin&version=2} {add=[12 (1412149794339553280)]} 0 0
[junit4:junit4]   2> 4303 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10011 (1412149794340601856)]} 0 1
[junit4:junit4]   2> 4303 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[12 (-1412149794341650432)]} 0 0
[junit4:junit4]   2> 4306 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[12 (-1412149794344796160)]} 0 1
[junit4:junit4]   2> 4306 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10012 (1412149794343747584)]} 0 1
[junit4:junit4]   2> 4308 T603 C77 P60514 /update {wt=javabin&version=2} {add=[13 (1412149794346893312)]} 0 0
[junit4:junit4]   2> 4308 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10012 (1412149794346893312)]} 0 0
[junit4:junit4]   2> 4311 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10012 (-1412149794350039040)]} 0 1
[junit4:junit4]   2> 4311 T617 C78 P56045 /update {wt=javabin&version=2} {add=[13 (1412149794348990464)]} 0 1
[junit4:junit4]   2> 4313 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10012 (-1412149794352136192)]} 0 1
[junit4:junit4]   2> 4313 T603 C77 P60514 /update {wt=javabin&version=2} {add=[14 (1412149794352136192)]} 0 0
[junit4:junit4]   2> 4315 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10013 (1412149794354233344)]} 0 0
[junit4:junit4]   2> 4316 T618 C78 P56045 /update {wt=javabin&version=2} {add=[14 (1412149794354233344)]} 0 1
[junit4:junit4]   2> 4318 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10013 (1412149794357379072)]} 0 1
[junit4:junit4]   2> 4318 T602 C77 P60514 /update {wt=javabin&version=2} {add=[15 (1412149794357379072)]} 0 0
[junit4:junit4]   2> 4320 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10014 (1412149794359476224)]} 0 0
[junit4:junit4]   2> 4321 T618 C78 P56045 /update {wt=javabin&version=2} {add=[15 (1412149794360524800)]} 0 1
[junit4:junit4]   2> 4323 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10014 (1412149794361573376)]} 0 1
[junit4:junit4]   2> 4324 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[15 (-1412149794363670528)]} 0 0
[junit4:junit4]   2> 4326 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10015 (1412149794364719104)]} 0 1
[junit4:junit4]   2> 4326 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[15 (-1412149794365767680)]} 0 0
[junit4:junit4]   2> 4329 T602 C77 P60514 /update {wt=javabin&version=2} {add=[16 (1412149794367864832)]} 0 1
[junit4:junit4]   2> 4332 T617 C78 P56045 /update {wt=javabin&version=2} {add=[16 (1412149794371010560)]} 0 1
[junit4:junit4]   2> 4332 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10015 (1412149794367864832)]} 0 5
[junit4:junit4]   2> 4334 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10015 (-1412149794374156289)]} 0 0
[junit4:junit4]   2> 4335 T602 C77 P60514 /update {wt=javabin&version=2} {add=[17 (1412149794374156288)]} 0 1
[junit4:junit4]   2> 4336 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10015 (-1412149794376253440)]} 0 0
[junit4:junit4]   2> 4337 T617 C78 P56045 /update {wt=javabin&version=2} {add=[17 (1412149794377302016)]} 0 0
[junit4:junit4]   2> 4339 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10016 (1412149794378350592)]} 0 1
[junit4:junit4]   2> 4340 T603 C77 P60514 /update {wt=javabin&version=2} {add=[18 (1412149794379399168)]} 0 1
[junit4:junit4]   2> 4342 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10016 (1412149794381496320)]} 0 1
[junit4:junit4]   2> 4342 T618 C78 P56045 /update {wt=javabin&version=2} {add=[18 (1412149794382544896)]} 0 0
[junit4:junit4]   2> 4345 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10017 (1412149794384642048)]} 0 1
[junit4:junit4]   2> 4345 T602 C77 P60514 /update {wt=javabin&version=2} {add=[19 (1412149794384642049)]} 0 1
[junit4:junit4]   2> 4350 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10017 (1412149794390933504)]} 0 0
[junit4:junit4]   2> 4351 T617 C78 P56045 /update {wt=javabin&version=2} {add=[19 (1412149794390933505)]} 0 1
[junit4:junit4]   2> 4353 T603 C77 P60514 /update {wt=javabin&version=2} {add=[20 (1412149794394079232)]} 0 0
[junit4:junit4]   2> 4356 T617 C78 P56045 /update {wt=javabin&version=2} {add=[20 (1412149794396176384)]} 0 1
[junit4:junit4]   2> 4357 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10018 (1412149794393030656)]} 0 5
[junit4:junit4]   2> 4358 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[18 (-1412149794399322112)]} 0 0
[junit4:junit4]   2> 4360 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[18 (-1412149794401419264)]} 0 0
[junit4:junit4]   2> 4360 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10018 (1412149794400370688)]} 0 1
[junit4:junit4]   2> 4364 T603 C77 P60514 /update {wt=javabin&version=2} {add=[21 (1412149794405613568)]} 0 0
[junit4:junit4]   2> 4365 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10019 (1412149794405613569)]} 0 1
[junit4:junit4]   2> 4367 T617 C78 P56045 /update {wt=javabin&version=2} {add=[21 (1412149794407710720)]} 0 1
[junit4:junit4]   2> 4369 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[19 (-1412149794410856448)]} 0 0
[junit4:junit4]   2> 4372 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10019 (1412149794408759296)]} 0 5
[junit4:junit4]   2> 4372 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[19 (-1412149794414002176)]} 0 0
[junit4:junit4]   2> 4375 T602 C77 P60514 /update {wt=javabin&version=2} {add=[22 (1412149794417147905)]} 0 1
[junit4:junit4]   2> 4379 T617 C78 P56045 /update {wt=javabin&version=2} {add=[22 (1412149794420293632)]} 0 1
[junit4:junit4]   2> 4380 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10020 (1412149794417147904)]} 0 6
[junit4:junit4]   2> 4382 T602 C77 P60514 /update {wt=javabin&version=2} {add=[23 (1412149794423439360)]} 0 1
[junit4:junit4]   2> 4383 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10020 (1412149794424487936)]} 0 1
[junit4:junit4]   2> 4384 T618 C78 P56045 /update {wt=javabin&version=2} {add=[23 (1412149794426585088)]} 0 0
[junit4:junit4]   2> 4385 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10018 (-1412149794427633664)]} 0 0
[junit4:junit4]   2> 4387 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10018 (-1412149794429730816)]} 0 0
[junit4:junit4]   2> 4387 T603 C77 P60514 /update {wt=javabin&version=2} {add=[24 (1412149794429730816)]} 0 1
[junit4:junit4]   2> 4389 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10021 (1412149794431827968)]} 0 0
[junit4:junit4]   2> 4390 T618 C78 P56045 /update {wt=javabin&version=2} {add=[24 (1412149794431827968)]} 0 1
[junit4:junit4]   2> 4392 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10021 (1412149794433925120)]} 0 1
[junit4:junit4]   2> 4392 T602 C77 P60514 /update {wt=javabin&version=2} {add=[25 (1412149794434973696)]} 0 0
[junit4:junit4]   2> 4394 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10019 (-1412149794437070848)]} 0 1
[junit4:junit4]   2> 4395 T617 C78 P56045 /update {wt=javabin&version=2} {add=[25 (1412149794437070848)]} 0 1
[junit4:junit4]   2> 4396 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10019 (-1412149794439168000)]} 0 1
[junit4:junit4]   2> 4396 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[20 (-1412149794439168000)]} 0 0
[junit4:junit4]   2> 4398 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10022 (1412149794441265152)]} 0 0
[junit4:junit4]   2> 4399 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[20 (-1412149794442313728)]} 0 0
[junit4:junit4]   2> 4401 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10022 (1412149794443362304)]} 0 1
[junit4:junit4]   2> 4402 T602 C77 P60514 /update {wt=javabin&version=2} {add=[26 (1412149794444410880)]} 0 1
[junit4:junit4]   2> 4403 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10023 (1412149794446508032)]} 0 0
[junit4:junit4]   2> 4405 T617 C78 P56045 /update {wt=javabin&version=2} {add=[26 (1412149794447556608)]} 0 1
[junit4:junit4]   2> 4405 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10023 (1412149794448605184)]} 0 0
[junit4:junit4]   2> 4407 T603 C77 P60514 /update {wt=javabin&version=2} {add=[27 (1412149794450702336)]} 0 0
[junit4:junit4]   2> 4408 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10024 (1412149794450702337)]} 0 1
[junit4:junit4]   2> 4411 T611 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4411 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10024 (1412149794453848064)]} 0 1
[junit4:junit4]   2> 4414 T618 C78 P56045 /update {wt=javabin&version=2} {add=[27 (1412149794452799488)]} 0 5
[junit4:junit4]   2> 4415 T624 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4415 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10025 (1412149794458042368)]} 0 1
[junit4:junit4]   2> 4415 T636 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4415 T644 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4415 T610 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4415 T640 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4425 T603 C77 P60514 /update {wt=javabin&version=2} {add=[28 (1412149794468528128)]} 0 1
[junit4:junit4]   2>  C76_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:57288/solr, node_name=127.0.0.1:57288_solr, state=recovering, core=collection1, collection=collection1, roles=null, shard=shard1}
[junit4:junit4]   2> 4448 T629 C76 P57288 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1346730985482/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@86376f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4448 T629 C76 P57288 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4450 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 4450 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=3 
[junit4:junit4]   2> 4451 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10025 (1412149794471673856)]} 0 26
[junit4:junit4]   2> 4451 T617 C78 P56045 /update {wt=javabin&version=2} {add=[28 (1412149794471673857)]} 0 24
[junit4:junit4]   2> 4453 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10020 (-1412149794498936832)]} 0 0
[junit4:junit4]   2> 4459 T602 C77 P60514 /update {wt=javabin&version=2} {add=[29 (1412149794498936833)]} 0 6
[junit4:junit4]   2> 4459 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4460 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10020 (-1412149794501033984)]} 0 5
[junit4:junit4]   2> 4463 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10026 (1412149794508374016)]} 0 2
[junit4:junit4]   2> 4464 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4465 T618 C78 P56045 /update {wt=javabin&version=2} {add=[29 (1412149794507325440)]} 0 4
[junit4:junit4]   2> 4467 T602 C77 P60514 /update {wt=javabin&version=2} {add=[30 (1412149794513616896)]} 0 0
[junit4:junit4]   2> 4467 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4468 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10026 (1412149794511519744)]} 0 3
[junit4:junit4]   2> 4470 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10027 (1412149794516762624)]} 0 0
[junit4:junit4]   2> 4472 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4473 T618 C78 P56045 /update {wt=javabin&version=2} {add=[30 (1412149794515714048)]} 0 4
[junit4:junit4]   2> 4476 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4477 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10027 (1412149794518859776)]} 0 5
[junit4:junit4]   2> 4479 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[25 (-1412149794526199809)]} 0 0
[junit4:junit4]   2> 4479 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10028 (1412149794526199808)]} 0 0
[junit4:junit4]   2> 4483 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4484 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[25 (-1412149794529345536)]} 0 2
[junit4:junit4]   2> 4484 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4485 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10028 (1412149794529345537)]} 0 3
[junit4:junit4]   2> 4487 T603 C77 P60514 /update {wt=javabin&version=2} {add=[31 (1412149794533539840)]} 0 1
[junit4:junit4]   2> 4487 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10029 (1412149794534588416)]} 0 0
[junit4:junit4]   2> 4492 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4492 T618 C78 P56045 /update {wt=javabin&version=2} {add=[31 (1412149794536685568)]} 0 3
[junit4:junit4]   2> 4494 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[27 (-1412149794541928448)]} 0 0
[junit4:junit4]   2> 4499 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 4500 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10029 (1412149794536685569)]} 0 11
[junit4:junit4]   2> 4500 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4501 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[27 (-1412149794544025600)]} 0 5
[junit4:junit4]   2> 4503 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10030 (1412149794550317056)]} 0 1
[junit4:junit4]   2> 4503 T603 C77 P60514 /update {wt=javabin&version=2} {add=[32 (1412149794551365632)]} 0 0
[junit4:junit4]   2> 4509 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4509 T617 C78 P56045 /update {wt=javabin&version=2} {add=[32 (1412149794554511360)]} 0 3
[junit4:junit4]   2> 4511 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 4512 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10030 (1412149794553462784)]} 0 7
[junit4:junit4]   2> 4513 T603 C77 P60514 /update {wt=javabin&version=2} {add=[33 (1412149794560802816)]} 0 1
[junit4:junit4]   2> 4514 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10025 (-1412149794562899968)]} 0 0
[junit4:junit4]   2> 4518 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4518 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4518 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10025 (-1412149794564997120)]} 0 2
[junit4:junit4]   2> 4519 T618 C78 P56045 /update {wt=javabin&version=2} {add=[33 (1412149794563948544)]} 0 4
[junit4:junit4]   2> 4521 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10031 (1412149794569191424)]} 0 1
[junit4:junit4]   2> 4521 T603 C77 P60514 /update {wt=javabin&version=2} {add=[34 (1412149794570240000)]} 0 0
[junit4:junit4]   2> 4525 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4526 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4526 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10031 (1412149794572337152)]} 0 3
[junit4:junit4]   2> 4526 T617 C78 P56045 /update {wt=javabin&version=2} {add=[34 (1412149794572337153)]} 0 3
[junit4:junit4]   2> 4528 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10027 (-1412149794577580032)]} 0 0
[junit4:junit4]   2> 4529 T602 C77 P60514 /update {wt=javabin&version=2} {add=[35 (1412149794577580033)]} 0 1
[junit4:junit4]   2> 4532 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4533 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10027 (-1412149794580725760)]} 0 3
[junit4:junit4]   2> 4534 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4534 T618 C78 P56045 /update {wt=javabin&version=2} {add=[35 (1412149794580725761)]} 0 3
[junit4:junit4]   2> 4535 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10032 (1412149794584920064)]} 0 0
[junit4:junit4]   2> 4536 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[29 (-1412149794585968640)]} 0 0
[junit4:junit4]   2> 4540 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4540 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4540 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10032 (1412149794587017216)]} 0 3
[junit4:junit4]   2> 4541 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[29 (-1412149794588065792)]} 0 3
[junit4:junit4]   2> 4543 T602 C77 P60514 /update {wt=javabin&version=2} {add=[36 (1412149794593308672)]} 0 0
[junit4:junit4]   2> 4548 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4548 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10033 (1412149794592260096)]} 0 6
[junit4:junit4]   2> 4549 T617 C78 P56045 /update {wt=javabin&version=2} {add=[36 (1412149794595405824)]} 0 4
[junit4:junit4]   2> 4552 T603 C77 P60514 /update {wt=javabin&version=2} {add=[37 (1412149794601697280)]} 0 1
[junit4:junit4]   2> 4553 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4554 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10033 (1412149794601697280)]} 0 4
[junit4:junit4]   2> 4556 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10034 (1412149794606940160)]} 0 0
[junit4:junit4]   2> 4561 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4566 T617 C78 P56045 /update {wt=javabin&version=2} {add=[37 (1412149794604843008)]} 0 12
[junit4:junit4]   2> 4566 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 4567 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10034 (1412149794610085888)]} 0 8
[junit4:junit4]   2> 4569 T603 C77 P60514 /update {wt=javabin&version=2} {add=[38 (1412149794619523072)]} 0 1
[junit4:junit4]   2> 4573 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10035 (1412149794620571648)]} 0 4
[junit4:junit4]   2> 4574 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4575 T618 C78 P56045 /update {wt=javabin&version=2} {add=[38 (1412149794622668800)]} 0 4
[junit4:junit4]   2> 4577 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[31 (-1412149794628960256)]} 0 0
[junit4:junit4]   2> 4578 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4578 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10035 (1412149794626863104)]} 0 3
[junit4:junit4]   2> 4580 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10029 (-1412149794632105984)]} 0 0
[junit4:junit4]   2> 4581 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4581 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[31 (-1412149794631057408)]} 0 2
[junit4:junit4]   2> 4584 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4589 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10029 (-1412149794634203136)]} 0 7
[junit4:junit4]   2> 4594 T602 C77 P60514 /update {wt=javabin&version=2} {add=[39 (1412149794642591744)]} 0 4
[junit4:junit4]   2> 4594 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10036 (1412149794643640320)]} 0 3
[junit4:junit4]   2> 4599 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4599 T617 C78 P56045 /update {wt=javabin&version=2} {add=[39 (1412149794648883200)]} 0 3
[junit4:junit4]   2> 4599 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4600 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10036 (1412149794648883201)]} 0 4
[junit4:junit4]   2> 4601 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[32 (-1412149794654126080)]} 0 0
[junit4:junit4]   2> 4602 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10037 (1412149794655174656)]} 0 0
[junit4:junit4]   2> 4605 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4606 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[32 (-1412149794656223232)]} 0 3
[junit4:junit4]   2> 4607 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4607 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10037 (1412149794657271808)]} 0 3
[junit4:junit4]   2> 4608 T602 C77 P60514 /update {wt=javabin&version=2} {add=[40 (1412149794661466112)]} 0 0
[junit4:junit4]   2> 4610 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10038 (1412149794662514688)]} 0 1
[junit4:junit4]   2> 4612 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4613 T617 C78 P56045 /update {wt=javabin&version=2} {add=[40 (1412149794663563264)]} 0 3
[junit4:junit4]   2> 4615 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4615 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10038 (1412149794665660416)]} 0 3
[junit4:junit4]   2> 4616 T603 C77 P60514 /update {wt=javabin&version=2} {add=[41 (1412149794668806144)]} 0 1
[junit4:junit4]   2> 4618 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10031 (-1412149794671951872)]} 0 1
[junit4:junit4]   2> 4647 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4648 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4648 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10031 (-1412149794700263425)]} 0 3
[junit4:junit4]   2> 4648 T618 C78 P56045 /update {wt=javabin&version=2} {add=[41 (1412149794700263424)]} 0 30
[junit4:junit4]   2> 4650 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[34 (-1412149794705506305)]} 0 0
[junit4:junit4]   2> 4650 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10039 (1412149794705506304)]} 0 0
[junit4:junit4]   2> 4654 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4655 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[34 (-1412149794707603456)]} 0 3
[junit4:junit4]   2> 4657 T602 C77 P60514 /update {wt=javabin&version=2} {add=[42 (1412149794712846336)]} 0 1
[junit4:junit4]   2> 4662 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4662 T618 C78 P56045 /update {wt=javabin&version=2} {add=[42 (1412149794714943488)]} 0 3
[junit4:junit4]   2> 4665 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[35 (-1412149794721234944)]} 0 1
[junit4:junit4]   2> 4669 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4669 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 4670 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[35 (-1412149794723332096)]} 0 3
[junit4:junit4]   2> 4670 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10039 (1412149794707603457)]} 0 18
[junit4:junit4]   2> 4672 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10032 (-1412149794728574976)]} 0 0
[junit4:junit4]   2> 4676 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4676 T602 C77 P60514 /update {wt=javabin&version=2} {add=[43 (1412149794732769280)]} 0 0
[junit4:junit4]   2> 4677 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10032 (-1412149794730672128)]} 0 3
[junit4:junit4]   2> 4679 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10040 (1412149794734866432)]} 0 1
[junit4:junit4]   2> 4681 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4682 T618 C78 P56045 /update {wt=javabin&version=2} {add=[43 (1412149794734866432)]} 0 4
[junit4:junit4]   2> 4684 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4684 T602 C77 P60514 /update {wt=javabin&version=2} {add=[44 (1412149794741157888)]} 0 0
[junit4:junit4]   2> 4685 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10040 (1412149794738012160)]} 0 4
[junit4:junit4]   2> 4687 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10041 (1412149794744303616)]} 0 0
[junit4:junit4]   2> 4689 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4689 T618 C78 P56045 /update {wt=javabin&version=2} {add=[44 (1412149794743255040)]} 0 3
[junit4:junit4]   2> 4692 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4692 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10041 (1412149794746400768)]} 0 3
[junit4:junit4]   2> 4694 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10034 (-1412149794751643648)]} 0 0
[junit4:junit4]   2> 4698 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4698 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10034 (-1412149794753740800)]} 0 2
[junit4:junit4]   2> 4701 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10042 (1412149794758983680)]} 0 1
[junit4:junit4]   2> 4705 T602 C77 P60514 /update {wt=javabin&version=2} {add=[45 (1412149794749546496)]} 0 14
[junit4:junit4]   2> 4706 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4706 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10042 (1412149794761080832)]} 0 3
[junit4:junit4]   2> 4709 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10035 (-1412149794767372288)]} 0 0
[junit4:junit4]   2> 4710 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4710 T618 C78 P56045 /update {wt=javabin&version=2} {add=[45 (1412149794765275136)]} 0 3
[junit4:junit4]   2> 4713 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4713 T602 C77 P60514 /update {wt=javabin&version=2} {add=[46 (1412149794770518016)]} 0 1
[junit4:junit4]   2> 4713 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10035 (-1412149794769469440)]} 0 2
[junit4:junit4]   2> 4716 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10043 (1412149794774712320)]} 0 1
[junit4:junit4]   2> 4718 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4719 T618 C78 P56045 /update {wt=javabin&version=2} {add=[46 (1412149794773663744)]} 0 4
[junit4:junit4]   2> 4721 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[38 (-1412149794779955200)]} 0 0
[junit4:junit4]   2> 4721 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4721 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10043 (1412149794776809472)]} 0 3
[junit4:junit4]   2> 4727 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10044 (1412149794782052352)]} 0 4
[junit4:junit4]   2> 4727 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4728 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[38 (-1412149794782052352)]} 0 5
[junit4:junit4]   2> 4730 T602 C77 P60514 /update {wt=javabin&version=2} {add=[47 (1412149794789392384)]} 0 1
[junit4:junit4]   2> 4735 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4736 T618 C78 P56045 /update {wt=javabin&version=2} {add=[47 (1412149794791489536)]} 0 4
[junit4:junit4]   2> 4738 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[39 (-1412149794797780992)]} 0 0
[junit4:junit4]   2> 4741 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 4741 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10044 (1412149794788343808)]} 0 12
[junit4:junit4]   2> 4742 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4742 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[39 (-1412149794799878144)]} 0 2
[junit4:junit4]   2> 4744 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10045 (1412149794803023872)]} 0 1
[junit4:junit4]   2> 4745 T603 C77 P60514 /update {wt=javabin&version=2} {add=[48 (1412149794804072448)]} 0 1
[junit4:junit4]   2> 4749 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4750 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10045 (1412149794806169600)]} 0 4
[junit4:junit4]   2> 4751 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 4751 T617 C78 P56045 /update {wt=javabin&version=2} {add=[48 (1412149794807218176)]} 0 4
[junit4:junit4]   2> 4752 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10046 (1412149794812461056)]} 0 0
[junit4:junit4]   2> 4753 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[43 (-1412149794813509632)]} 0 0
[junit4:junit4]   2> 4757 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4757 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4757 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[43 (-1412149794815606784)]} 0 2
[junit4:junit4]   2> 4757 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10046 (1412149794814558208)]} 0 3
[junit4:junit4]   2> 4759 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10038 (-1412149794819801089)]} 0 0
[junit4:junit4]   2> 4760 T602 C77 P60514 /update {wt=javabin&version=2} {add=[49 (1412149794819801088)]} 0 1
[junit4:junit4]   2> 4763 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4764 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10038 (-1412149794821898240)]} 0 3
[junit4:junit4]   2> 4764 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4764 T618 C78 P56045 /update {wt=javabin&version=2} {add=[49 (1412149794822946816)]} 0 2
[junit4:junit4]   2> 4766 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10047 (1412149794827141120)]} 0 1
[junit4:junit4]   2> 4775 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4775 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10047 (1412149794829238272)]} 0 7
[junit4:junit4]   2> 4776 T603 C77 P60514 /update {wt=javabin&version=2} {add=[50 (1412149794827141121)]} 0 10
[junit4:junit4]   2> 4777 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10039 (-1412149794838675456)]} 0 0
[junit4:junit4]   2> 4781 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4781 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4781 T618 C78 P56045 /update {wt=javabin&version=2} {add=[50 (1412149794839724032)]} 0 3
[junit4:junit4]   2> 4782 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10039 (-1412149794840772608)]} 0 3
[junit4:junit4]   2> 4784 T602 C77 P60514 /update {wt=javabin&version=2} {add=[51 (1412149794844966912)]} 0 1
[junit4:junit4]   2> 4785 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10048 (1412149794846015488)]} 0 1
[junit4:junit4]   2> 4788 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4789 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4789 T617 C78 P56045 /update {wt=javabin&version=2} {add=[51 (1412149794848112640)]} 0 3
[junit4:junit4]   2> 4789 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10048 (1412149794849161216)]} 0 2
[junit4:junit4]   2> 4791 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[44 (-1412149794853355520)]} 0 0
[junit4:junit4]   2> 4792 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10043 (-1412149794853355521)]} 0 1
[junit4:junit4]   2> 4797 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4797 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[44 (-1412149794857549824)]} 0 2
[junit4:junit4]   2> 4798 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4799 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10043 (-1412149794859646976)]} 0 2
[junit4:junit4]   2> 4800 T602 C77 P60514 /update {wt=javabin&version=2} {add=[52 (1412149794861744128)]} 0 1
[junit4:junit4]   2> 4801 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10049 (1412149794863841280)]} 0 0
[junit4:junit4]   2> 4806 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4807 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10049 (1412149794866987008)]} 0 4
[junit4:junit4]   2> 4809 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10050 (1412149794872229888)]} 0 0
[junit4:junit4]   2> 4811 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 4811 T617 C78 P56045 /update {wt=javabin&version=2} {add=[52 (1412149794864889856)]} 0 9
[junit4:junit4]   2> 4814 T603 C77 P60514 /update {wt=javabin&version=2} {add=[53 (1412149794877472768)]} 0 1
[junit4:junit4]   2> 4814 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4815 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10050 (1412149794875375616)]} 0 3
[junit4:junit4]   2> 4818 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10051 (1412149794880618496)]} 0 1
[junit4:junit4]   2> 4819 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4819 T617 C78 P56045 /update {wt=javabin&version=2} {add=[53 (1412149794879569920)]} 0 3
[junit4:junit4]   2> 4821 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[46 (-1412149794884812800)]} 0 0
[junit4:junit4]   2> 4822 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4822 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10051 (1412149794883764224)]} 0 3
[junit4:junit4]   2> 4824 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10044 (-1412149794887958528)]} 0 0
[junit4:junit4]   2> 4825 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4825 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[46 (-1412149794886909952)]} 0 2
[junit4:junit4]   2> 4827 T603 C77 P60514 /update {wt=javabin&version=2} {add=[54 (1412149794891104256)]} 0 0
[junit4:junit4]   2> 4828 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4828 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10044 (-1412149794890055680)]} 0 2
[junit4:junit4]   2> 4831 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10052 (1412149794894249984)]} 0 1
[junit4:junit4]   2> 4832 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4832 T617 C78 P56045 /update {wt=javabin&version=2} {add=[54 (1412149794893201408)]} 0 3
[junit4:junit4]   2> 4834 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[49 (-1412149794898444288)]} 0 0
[junit4:junit4]   2> 4835 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4835 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10052 (1412149794897395712)]} 0 3
[junit4:junit4]   2> 4839 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10053 (1412149794902638592)]} 0 1
[junit4:junit4]   2> 4840 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4840 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[49 (-1412149794902638592)]} 0 2
[junit4:junit4]   2> 4843 T603 C77 P60514 /update {wt=javabin&version=2} {add=[55 (1412149794906832896)]} 0 1
[junit4:junit4]   2> 4843 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4843 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10053 (1412149794905784320)]} 0 3
[junit4:junit4]   2> 4845 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10046 (-1412149794909978624)]} 0 0
[junit4:junit4]   2> 4847 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4847 T617 C78 P56045 /update {wt=javabin&version=2} {add=[55 (1412149794909978624)]} 0 2
[junit4:junit4]   2> 4849 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4849 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10046 (-1412149794912075776)]} 0 2
[junit4:junit4]   2> 4849 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[52 (-1412149794914172928)]} 0 0
[junit4:junit4]   2> 4854 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4854 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[52 (-1412149794917318656)]} 0 2
[junit4:junit4]   2> 4856 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10054 (1412149794916270080)]} 0 5
[junit4:junit4]   2> 4860 T603 C77 P60514 /update {wt=javabin&version=2} {add=[56 (1412149794921512960)]} 0 4
[junit4:junit4]   2> 4860 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4860 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10054 (1412149794923610112)]} 0 2
[junit4:junit4]   2> 4862 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10049 (-1412149794927804416)]} 0 0
[junit4:junit4]   2> 4868 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4870 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 4870 T617 C78 P56045 /update {wt=javabin&version=2} {add=[56 (1412149794927804416)]} 0 8
[junit4:junit4]   2> 4870 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10049 (-1412149794929901568)]} 0 6
[junit4:junit4]   2> 4873 T603 C77 P60514 /update {wt=javabin&version=2} {add=[57 (1412149794938290176)]} 0 1
[junit4:junit4]   2> 4873 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10055 (1412149794938290177)]} 0 1
[junit4:junit4]   2> 4878 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4879 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10055 (1412149794941435905)]} 0 4
[junit4:junit4]   2> 4881 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 4881 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10052 (-1412149794947727360)]} 0 0
[junit4:junit4]   2> 4881 T618 C78 P56045 /update {wt=javabin&version=2} {add=[57 (1412149794941435904)]} 0 6
[junit4:junit4]   2> 4885 T602 C77 P60514 /update {wt=javabin&version=2} {add=[58 (1412149794950873088)]} 0 1
[junit4:junit4]   2> 4886 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4886 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10052 (-1412149794949824512)]} 0 3
[junit4:junit4]   2> 4889 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10056 (1412149794955067392)]} 0 1
[junit4:junit4]   2> 4890 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4890 T618 C78 P56045 /update {wt=javabin&version=2} {add=[58 (1412149794955067392)]} 0 2
[junit4:junit4]   2> 4892 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[55 (-1412149794959261696)]} 0 0
[junit4:junit4]   2> 4893 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4894 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10056 (1412149794958213120)]} 0 3
[junit4:junit4]   2> 4896 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10057 (1412149794963456000)]} 0 0
[junit4:junit4]   2> 4896 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4897 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[55 (-1412149794961358848)]} 0 3
[junit4:junit4]   2> 4899 T602 C77 P60514 /update {wt=javabin&version=2} {add=[59 (1412149794966601728)]} 0 1
[junit4:junit4]   2> 4901 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4906 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10057 (1412149794965553152)]} 0 8
[junit4:junit4]   2> 4906 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4907 T618 C78 P56045 /update {wt=javabin&version=2} {add=[59 (1412149794970796032)]} 0 3
[junit4:junit4]   2> 4908 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10058 (1412149794976038912)]} 0 0
[junit4:junit4]   2> 4909 T603 C77 P60514 /update {wt=javabin&version=2} {add=[60 (1412149794976038913)]} 0 1
[junit4:junit4]   2> 4912 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4913 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10058 (1412149794978136064)]} 0 3
[junit4:junit4]   2> 4913 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4914 T617 C78 P56045 /update {wt=javabin&version=2} {add=[60 (1412149794979184640)]} 0 4
[junit4:junit4]   2> 4915 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10055 (-1412149794983378944)]} 0 0
[junit4:junit4]   2> 4916 T602 C77 P60514 /update {wt=javabin&version=2} {add=[61 (1412149794984427520)]} 0 0
[junit4:junit4]   2> 4919 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4919 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10055 (-1412149794985476096)]} 0 2
[junit4:junit4]   2> 4921 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4921 T618 C78 P56045 /update {wt=javabin&version=2} {add=[61 (1412149794986524672)]} 0 3
[junit4:junit4]   2> 4924 T603 C77 P60514 /update {wt=javabin&version=2} {add=[62 (1412149794992816128)]} 0 0
[junit4:junit4]   2> 4927 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10059 (1412149794995961856)]} 0 0
[junit4:junit4]   2> 4932 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4932 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10059 (1412149794998059008)]} 0 3
[junit4:junit4]   2> 4935 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10060 (1412149795004350464)]} 0 0
[junit4:junit4]   2> 4936 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 4936 T618 C78 P56045 /update {wt=javabin&version=2} {add=[62 (1412149794994913280)]} 0 10
[junit4:junit4]   2> 4941 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4942 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10060 (1412149795007496192)]} 0 5
[junit4:junit4]   2> 4942 T602 C77 P60514 /update {wt=javabin&version=2} {add=[63 (1412149795007496192)]} 0 4
[junit4:junit4]   2> 4949 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10061 (1412149795013787648)]} 0 5
[junit4:junit4]   2> 4951 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4951 T617 C78 P56045 /update {wt=javabin&version=2} {add=[63 (1412149795017981952)]} 0 3
[junit4:junit4]   2> 4954 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[56 (-1412149795024273408)]} 0 0
[junit4:junit4]   2> 4956 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4958 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10061 (1412149795023224832)]} 0 5
[junit4:junit4]   2> 4960 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4961 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[56 (-1412149795028467712)]} 0 3
[junit4:junit4]   2> 4962 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10062 (1412149795032662016)]} 0 1
[junit4:junit4]   2> 4969 T602 C77 P60514 /update {wt=javabin&version=2} {add=[64 (1412149795034759168)]} 0 5
[junit4:junit4]   2> 4971 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4972 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10062 (1412149795035807744)]} 0 8
[junit4:junit4]   2> 4974 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4974 T618 C78 P56045 /update {wt=javabin&version=2} {add=[64 (1412149795042099200)]} 0 3
[junit4:junit4]   2> 4974 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10063 (1412149795045244928)]} 0 0
[junit4:junit4]   2> 4977 T603 C77 P60514 /update {wt=javabin&version=2} {add=[65 (1412149795047342080)]} 0 1
[junit4:junit4]   2> 4979 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4980 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10063 (1412149795047342080)]} 0 4
[junit4:junit4]   2> 4982 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4982 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10056 (-1412149795053633536)]} 0 0
[junit4:junit4]   2> 4982 T617 C78 P56045 /update {wt=javabin&version=2} {add=[65 (1412149795050487808)]} 0 3
[junit4:junit4]   2> 4984 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[57 (-1412149795055730688)]} 0 0
[junit4:junit4]   2> 4986 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4986 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10056 (-1412149795055730688)]} 0 2
[junit4:junit4]   2> 4988 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4989 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10064 (1412149795059924992)]} 0 1
[junit4:junit4]   2> 4989 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[57 (-1412149795057827840)]} 0 3
[junit4:junit4]   2> 4991 T603 C77 P60514 /update {wt=javabin&version=2} {add=[66 (1412149795063070720)]} 0 0
[junit4:junit4]   2> 4993 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4994 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10064 (1412149795063070720)]} 0 3
[junit4:junit4]   2> 4996 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10065 (1412149795068313600)]} 0 0
[junit4:junit4]   2> 5001 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5001 T617 C78 P56045 /update {wt=javabin&version=2} {add=[66 (1412149795065167872)]} 0 8
[junit4:junit4]   2> 5007 T603 C77 P60514 /update {wt=javabin&version=2} {add=[67 (1412149795075653632)]} 0 4
[junit4:junit4]   2> 5012 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5013 T617 C78 P56045 /update {wt=javabin&version=2} {add=[67 (1412149795081945088)]} 0 4
[junit4:junit4]   2> 5013 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=12 
[junit4:junit4]   2> 5014 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10065 (1412149795070410752)]} 0 16
[junit4:junit4]   2> 5015 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[58 (-1412149795088236544)]} 0 0
[junit4:junit4]   2> 5016 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10057 (-1412149795089285120)]} 0 0
[junit4:junit4]   2> 5019 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5020 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5020 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[58 (-1412149795091382272)]} 0 3
[junit4:junit4]   2> 5020 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10057 (-1412149795091382273)]} 0 2
[junit4:junit4]   2> 5023 T602 C77 P60514 /update {wt=javabin&version=2} {add=[68 (1412149795095576576)]} 0 1
[junit4:junit4]   2> 5024 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10066 (1412149795096625152)]} 0 2
[junit4:junit4]   2> 5028 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5029 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5029 T617 C78 P56045 /update {wt=javabin&version=2} {add=[68 (1412149795098722304)]} 0 4
[junit4:junit4]   2> 5029 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10066 (1412149795099770880)]} 0 3
[junit4:junit4]   2> 5031 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[59 (-1412149795105013760)]} 0 0
[junit4:junit4]   2> 5032 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10067 (1412149795106062336)]} 0 1
[junit4:junit4]   2> 5035 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5036 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[59 (-1412149795107110912)]} 0 3
[junit4:junit4]   2> 5038 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5038 T602 C77 P60514 /update {wt=javabin&version=2} {add=[69 (1412149795112353792)]} 0 0
[junit4:junit4]   2> 5039 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10067 (1412149795108159488)]} 0 5
[junit4:junit4]   2> 5043 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10058 (-1412149795117596672)]} 0 1
[junit4:junit4]   2> 5043 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5043 T618 C78 P56045 /update {wt=javabin&version=2} {add=[69 (1412149795115499520)]} 0 3
[junit4:junit4]   2> 5046 T602 C77 P60514 /update {wt=javabin&version=2} {add=[70 (1412149795119693824)]} 0 1
[junit4:junit4]   2> 5046 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5047 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10058 (-1412149795119693824)]} 0 3
[junit4:junit4]   2> 5049 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10068 (1412149795123888128)]} 0 0
[junit4:junit4]   2> 5050 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5051 T618 C78 P56045 /update {wt=javabin&version=2} {add=[70 (1412149795122839552)]} 0 3
[junit4:junit4]   2> 5053 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[60 (-1412149795128082432)]} 0 0
[junit4:junit4]   2> 5053 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5054 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10068 (1412149795125985280)]} 0 3
[junit4:junit4]   2> 5056 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10059 (-1412149795131228160)]} 0 1
[junit4:junit4]   2> 5057 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5057 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[60 (-1412149795130179584)]} 0 2
[junit4:junit4]   2> 5060 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5060 T602 C77 P60514 /update {wt=javabin&version=2} {add=[71 (1412149795134373888)]} 0 1
[junit4:junit4]   2> 5060 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10059 (-1412149795133325312)]} 0 2
[junit4:junit4]   2> 5063 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10069 (1412149795137519616)]} 0 1
[junit4:junit4]   2> 5064 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5064 T618 C78 P56045 /update {wt=javabin&version=2} {add=[71 (1412149795137519616)]} 0 2
[junit4:junit4]   2> 5067 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[62 (-1412149795142762496)]} 0 1
[junit4:junit4]   2> 5070 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5071 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5071 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10069 (1412149795139616768)]} 0 7
[junit4:junit4]   2> 5071 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[62 (-1412149795144859648)]} 0 2
[junit4:junit4]   2> 5074 T603 C77 P60514 /update {wt=javabin&version=2} {add=[72 (1412149795149053953)]} 0 1
[junit4:junit4]   2> 5077 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10070 (1412149795149053952)]} 0 4
[junit4:junit4]   2> 5082 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5082 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10070 (1412149795155345408)]} 0 3
[junit4:junit4]   2> 5084 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10060 (-1412149795160588288)]} 0 0
[junit4:junit4]   2> 5085 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 5086 T618 C78 P56045 /update {wt=javabin&version=2} {add=[72 (1412149795154296832)]} 0 8
[junit4:junit4]   2> 5088 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5089 T602 C77 P60514 /update {wt=javabin&version=2} {add=[73 (1412149795164782592)]} 0 1
[junit4:junit4]   2> 5089 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10060 (-1412149795162685440)]} 0 3
[junit4:junit4]   2> 5091 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10071 (1412149795167928320)]} 0 0
[junit4:junit4]   2> 5093 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5094 T618 C78 P56045 /update {wt=javabin&version=2} {add=[73 (1412149795167928320)]} 0 3
[junit4:junit4]   2> 5096 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5096 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[63 (-1412149795173171200)]} 0 0
[junit4:junit4]   2> 5097 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10071 (1412149795170025472)]} 0 4
[junit4:junit4]   2> 5099 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10062 (-1412149795176316928)]} 0 0
[junit4:junit4]   2> 5100 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5100 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[63 (-1412149795175268352)]} 0 2
[junit4:junit4]   2> 5102 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5103 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10062 (-1412149795178414080)]} 0 3
[junit4:junit4]   2> 5103 T602 C77 P60514 /update {wt=javabin&version=2} {add=[74 (1412149795179462656)]} 0 1
[junit4:junit4]   2> 5105 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10072 (1412149795182608384)]} 0 0
[junit4:junit4]   2> 5108 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5108 T617 C78 P56045 /update {wt=javabin&version=2} {add=[74 (1412149795182608384)]} 0 3
[junit4:junit4]   2> 5110 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5111 T603 C77 P60514 /update {wt=javabin&version=2} {add=[75 (1412149795187851264)]} 0 1
[junit4:junit4]   2> 5111 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10072 (1412149795185754112)]} 0 4
[junit4:junit4]   2> 5113 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10073 (1412149795190996992)]} 0 0
[junit4:junit4]   2> 5115 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5116 T617 C78 P56045 /update {wt=javabin&version=2} {add=[75 (1412149795190996992)]} 0 3
[junit4:junit4]   2> 5117 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5118 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10073 (1412149795193094144)]} 0 3
[junit4:junit4]   2> 5118 T603 C77 P60514 /update {wt=javabin&version=2} {add=[76 (1412149795196239872)]} 0 0
[junit4:junit4]   2> 5120 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10063 (-1412149795198337024)]} 0 0
[junit4:junit4]   2> 5123 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5123 T618 C78 P56045 /update {wt=javabin&version=2} {add=[76 (1412149795198337024)]} 0 3
[junit4:junit4]   2> 5123 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5124 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10063 (-1412149795200434176)]} 0 2
[junit4:junit4]   2> 5125 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[64 (-1412149795203579904)]} 0 0
[junit4:junit4]   2> 5126 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10074 (1412149795204628480)]} 0 0
[junit4:junit4]   2> 5128 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5129 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[64 (-1412149795205677056)]} 0 2
[junit4:junit4]   2> 5139 T603 C77 P60514 /update {wt=javabin&version=2} {add=[77 (1412149795218259968)]} 0 1
[junit4:junit4]   2> 5144 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5145 T617 C78 P56045 /update {wt=javabin&version=2} {add=[77 (1412149795220357120)]} 0 4
[junit4:junit4]   2> 5147 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[66 (-1412149795226648576)]} 0 0
[junit4:junit4]   2> 5151 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5152 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[66 (-1412149795228745728)]} 0 3
[junit4:junit4]   2> 5153 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 5153 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10074 (1412149795206725632)]} 0 25
[junit4:junit4]   2> 5155 T603 C77 P60514 /update {wt=javabin&version=2} {add=[78 (1412149795233988608)]} 0 1
[junit4:junit4]   2> 5156 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10075 (1412149795235037184)]} 0 1
[junit4:junit4]   2> 5160 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5161 T618 C78 P56045 /update {wt=javabin&version=2} {add=[78 (1412149795237134336)]} 0 4
[junit4:junit4]   2> 5163 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[69 (-1412149795243425792)]} 0 0
[junit4:junit4]   2> 5163 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5164 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10075 (1412149795238182912)]} 0 6
[junit4:junit4]   2> 5166 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10076 (1412149795246571520)]} 0 0
[junit4:junit4]   2> 5167 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5167 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[69 (-1412149795245522944)]} 0 2
[junit4:junit4]   2> 5170 T602 C77 P60514 /update {wt=javabin&version=2} {add=[79 (1412149795249717248)]} 0 1
[junit4:junit4]   2> 5170 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5171 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10076 (1412149795248668672)]} 0 3
[junit4:junit4]   2> 5173 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10064 (-1412149795253911552)]} 0 0
[junit4:junit4]   2> 5175 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5175 T618 C78 P56045 /update {wt=javabin&version=2} {add=[79 (1412149795252862976)]} 0 3
[junit4:junit4]   2> 5177 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5177 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10064 (-1412149795256008704)]} 0 2
[junit4:junit4]   2> 5177 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[71 (-1412149795258105856)]} 0 0
[junit4:junit4]   2> 5180 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10077 (1412149795261251584)]} 0 1
[junit4:junit4]   2> 5181 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5182 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[71 (-1412149795261251584)]} 0 3
[junit4:junit4]   2> 5184 T602 C77 P60514 /update {wt=javabin&version=2} {add=[80 (1412149795265445888)]} 0 0
[junit4:junit4]   2> 5187 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5188 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10077 (1412149795263348736)]} 0 6
[junit4:junit4]   2> 5189 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5189 T617 C78 P56045 /update {wt=javabin&version=2} {add=[80 (1412149795267543040)]} 0 3
[junit4:junit4]   2> 5190 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10066 (-1412149795271737344)]} 0 0
[junit4:junit4]   2> 5191 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[72 (-1412149795272785920)]} 0 0
[junit4:junit4]   2> 5193 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5194 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10066 (-1412149795273834496)]} 0 2
[junit4:junit4]   2> 5195 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5195 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[72 (-1412149795274883072)]} 0 2
[junit4:junit4]   2> 5197 T602 C77 P60514 /update {wt=javabin&version=2} {add=[81 (1412149795279077376)]} 0 0
[junit4:junit4]   2> 5202 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5203 T618 C78 P56045 /update {wt=javabin&version=2} {add=[81 (1412149795281174528)]} 0 4
[junit4:junit4]   2> 5206 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10078 (1412149795278028800)]} 0 10
[junit4:junit4]   2> 5209 T602 C77 P60514 /update {wt=javabin&version=2} {add=[82 (1412149795287465984)]} 0 4
[junit4:junit4]   2> 5210 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5211 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10078 (1412149795290611712)]} 0 3
[junit4:junit4]   2> 5213 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10069 (-1412149795295854592)]} 0 0
[junit4:junit4]   2> 5217 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5217 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10069 (-1412149795297951744)]} 0 2
[junit4:junit4]   2> 5219 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5219 T617 C78 P56045 /update {wt=javabin&version=2} {add=[82 (1412149795293757440)]} 0 8
[junit4:junit4]   2> 5220 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10079 (1412149795302146048)]} 0 1
[junit4:junit4]   2> 5221 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[76 (-1412149795304243200)]} 0 0
[junit4:junit4]   2> 5225 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5225 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[76 (-1412149795306340352)]} 0 2
[junit4:junit4]   2> 5228 T603 C77 P60514 /update {wt=javabin&version=2} {add=[83 (1412149795310534656)]} 0 1
[junit4:junit4]   2> 5230 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 5230 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10079 (1412149795305291776)]} 0 8
[junit4:junit4]   2> 5233 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10071 (-1412149795316826112)]} 0 1
[junit4:junit4]   2> 5233 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5234 T618 C78 P56045 /update {wt=javabin&version=2} {add=[83 (1412149795313680384)]} 0 4
[junit4:junit4]   2> 5236 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[77 (-1412149795319971840)]} 0 0
[junit4:junit4]   2> 5236 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5237 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10071 (-1412149795318923264)]} 0 3
[junit4:junit4]   2> 5241 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5241 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[77 (-1412149795322068992)]} 0 3
[junit4:junit4]   2> 5241 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10080 (1412149795325214720)]} 0 0
[junit4:junit4]   2> 5243 T603 C77 P60514 /update {wt=javabin&version=2} {add=[84 (1412149795327311872)]} 0 0
[junit4:junit4]   2> 5246 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5246 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10080 (1412149795327311872)]} 0 3
[junit4:junit4]   2> 5248 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5251 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10072 (-1412149795335700480)]} 0 3
[junit4:junit4]   2> 5251 T617 C78 P56045 /update {wt=javabin&version=2} {add=[84 (1412149795329409024)]} 0 6
[junit4:junit4]   2> 5253 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[82 (-1412149795337797632)]} 0 0
[junit4:junit4]   2> 5255 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5256 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10072 (-1412149795337797632)]} 0 3
[junit4:junit4]   2> 5257 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5258 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[82 (-1412149795339894784)]} 0 3
[junit4:junit4]   2> 5258 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10081 (1412149795343040512)]} 0 0
[junit4:junit4]   2> 5260 T602 C77 P60514 /update {wt=javabin&version=2} {add=[85 (1412149795344089088)]} 0 1
[junit4:junit4]   2> 5264 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5265 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5265 T617 C78 P56045 /update {wt=javabin&version=2} {add=[85 (1412149795347234816)]} 0 3
[junit4:junit4]   2> 5266 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10081 (1412149795345137664)]} 0 6
[junit4:junit4]   2> 5268 T602 C77 P60514 /update {wt=javabin&version=2} {add=[86 (1412149795352477696)]} 0 1
[junit4:junit4]   2> 5268 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10082 (1412149795353526272)]} 0 0
[junit4:junit4]   2> 5272 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5272 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5272 T618 C78 P56045 /update {wt=javabin&version=2} {add=[86 (1412149795355623424)]} 0 2
[junit4:junit4]   2> 5273 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10082 (1412149795355623425)]} 0 3
[junit4:junit4]   2> 5275 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10076 (-1412149795360866305)]} 0 0
[junit4:junit4]   2> 5275 T603 C77 P60514 /update {wt=javabin&version=2} {add=[87 (1412149795360866304)]} 0 0
[junit4:junit4]   2> 5278 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5279 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5279 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10076 (-1412149795362963456)]} 0 2
[junit4:junit4]   2> 5280 T618 C78 P56045 /update {wt=javabin&version=2} {add=[87 (1412149795362963457)]} 0 3
[junit4:junit4]   2> 5282 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10083 (1412149795367157760)]} 0 1
[junit4:junit4]   2> 5282 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[84 (-1412149795368206336)]} 0 0
[junit4:junit4]   2> 5285 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5288 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[84 (-1412149795370303489)]} 0 4
[junit4:junit4]   2> 5289 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5290 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10083 (1412149795370303488)]} 0 6
[junit4:junit4]   2> 5290 T602 C77 P60514 /update {wt=javabin&version=2} {add=[88 (1412149795376594944)]} 0 0
[junit4:junit4]   2> 5292 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10077 (-1412149795377643520)]} 0 1
[junit4:junit4]   2> 5295 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5296 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10077 (-1412149795379740672)]} 0 3
[junit4:junit4]   2> 5298 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10084 (1412149795383934976)]} 0 1
[junit4:junit4]   2> 5298 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 5298 T618 C78 P56045 /update {wt=javabin&version=2} {add=[88 (1412149795378692096)]} 0 6
[junit4:junit4]   2> 5300 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[85 (-1412149795387080704)]} 0 0
[junit4:junit4]   2> 5303 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5303 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10084 (1412149795387080704)]} 0 3
[junit4:junit4]   2> 5304 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5305 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[85 (-1412149795389177856)]} 0 2
[junit4:junit4]   2> 5305 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10082 (-1412149795392323584)]} 0 0
[junit4:junit4]   2> 5307 T602 C77 P60514 /update {wt=javabin&version=2} {add=[89 (1412149795393372160)]} 0 1
[junit4:junit4]   2> 5309 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5311 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10082 (-1412149795394420736)]} 0 4
[junit4:junit4]   2> 5314 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5314 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10085 (1412149795400712192)]} 0 1
[junit4:junit4]   2> 5314 T617 C78 P56045 /update {wt=javabin&version=2} {add=[89 (1412149795396517888)]} 0 6
[junit4:junit4]   2> 5316 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[87 (-1412149795403857920)]} 0 0
[junit4:junit4]   2> 5318 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5319 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10085 (1412149795403857920)]} 0 3
[junit4:junit4]   2> 5320 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5320 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[87 (-1412149795405955072)]} 0 2
[junit4:junit4]   2> 5321 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10086 (1412149795409100800)]} 0 0
[junit4:junit4]   2> 5322 T603 C77 P60514 /update {wt=javabin&version=2} {add=[90 (1412149795410149376)]} 0 0
[junit4:junit4]   2> 5326 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5326 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10086 (1412149795411197952)]} 0 3
[junit4:junit4]   2> 5327 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5327 T618 C78 P56045 /update {wt=javabin&version=2} {add=[90 (1412149795412246528)]} 0 3
[junit4:junit4]   2> 5330 T602 C77 P60514 /update {wt=javabin&version=2} {add=[91 (1412149795417489408)]} 0 1
[junit4:junit4]   2> 5335 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10087 (1412149795416440832)]} 0 7
[junit4:junit4]   2> 5337 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5338 T618 C78 P56045 /update {wt=javabin&version=2} {add=[91 (1412149795423780864)]} 0 3
[junit4:junit4]   2> 5340 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5340 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10087 (1412149795425878016)]} 0 3
[junit4:junit4]   2> 5341 T603 C77 P60514 /update {wt=javabin&version=2} {add=[92 (1412149795427975168)]} 0 2
[junit4:junit4]   2> 5343 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10084 (-1412149795432169472)]} 0 1
[junit4:junit4]   2> 5345 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5346 T617 C78 P56045 /update {wt=javabin&version=2} {add=[92 (1412149795432169472)]} 0 3
[junit4:junit4]   2> 5346 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5347 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10084 (-1412149795433218048)]} 0 3
[junit4:junit4]   2> 5348 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[88 (-1412149795437412352)]} 0 0
[junit4:junit4]   2> 5349 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10088 (1412149795438460928)]} 0 1
[junit4:junit4]   2> 5351 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5355 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[88 (-1412149795439509504)]} 0 5
[junit4:junit4]   2> 5358 T603 C77 P60514 /update {wt=javabin&version=2} {add=[93 (1412149795447898112)]} 0 0
[junit4:junit4]   2> 5361 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5362 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10088 (1412149795440558080)]} 0 11
[junit4:junit4]   2> 5364 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10085 (-1412149795454189568)]} 0 0
[junit4:junit4]   2> 5367 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 5368 T618 C78 P56045 /update {wt=javabin&version=2} {add=[93 (1412149795451043840)]} 0 7
[junit4:junit4]   2> 5368 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5369 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10085 (-1412149795457335296)]} 0 3
[junit4:junit4]   2> 5370 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[91 (-1412149795459432448)]} 0 1
[junit4:junit4]   2> 5371 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10089 (1412149795461529600)]} 0 0
[junit4:junit4]   2> 5375 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5377 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5377 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10089 (1412149795464675328)]} 0 4
[junit4:junit4]   2> 5379 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[91 (-1412149795461529600)]} 0 8
[junit4:junit4]   2> 5380 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10087 (-1412149795470966784)]} 0 1
[junit4:junit4]   2> 5381 T603 C77 P60514 /update {wt=javabin&version=2} {add=[94 (1412149795470966785)]} 0 1
[junit4:junit4]   2> 5383 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5384 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10087 (-1412149795473063936)]} 0 3
[junit4:junit4]   2> 5386 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5386 T618 C78 P56045 /update {wt=javabin&version=2} {add=[94 (1412149795474112512)]} 0 3
[junit4:junit4]   2> 5386 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10090 (1412149795477258240)]} 0 0
[junit4:junit4]   2> 5391 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5393 T602 C77 P60514 /update {wt=javabin&version=2} {add=[95 (1412149795479355392)]} 0 5
[junit4:junit4]   2> 5393 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10090 (1412149795479355392)]} 0 5
[junit4:junit4]   2> 5396 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10091 (1412149795487744000)]} 0 1
[junit4:junit4]   2> 5398 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5399 T617 C78 P56045 /update {wt=javabin&version=2} {add=[95 (1412149795487744000)]} 0 3
[junit4:junit4]   2> 5401 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5401 T602 C77 P60514 /update {wt=javabin&version=2} {add=[96 (1412149795492986880)]} 0 0
[junit4:junit4]   2> 5402 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10091 (1412149795489841152)]} 0 4
[junit4:junit4]   2> 5404 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10092 (1412149795496132608)]} 0 1
[junit4:junit4]   2> 5406 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5407 T617 C78 P56045 /update {wt=javabin&version=2} {add=[96 (1412149795495084032)]} 0 4
[junit4:junit4]   2> 5408 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5409 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10092 (1412149795498229760)]} 0 3
[junit4:junit4]   2> 5409 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[93 (-1412149795501375488)]} 0 0
[junit4:junit4]   2> 5411 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10088 (-1412149795503472640)]} 0 0
[junit4:junit4]   2> 5413 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5413 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[93 (-1412149795503472640)]} 0 2
[junit4:junit4]   2> 5415 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5416 T603 C77 P60514 /update {wt=javabin&version=2} {add=[97 (1412149795508715520)]} 0 0
[junit4:junit4]   2> 5417 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10088 (-1412149795505569792)]} 0 4
[junit4:junit4]   2> 5419 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10093 (1412149795511861248)]} 0 0
[junit4:junit4]   2> 5420 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5421 T618 C78 P56045 /update {wt=javabin&version=2} {add=[97 (1412149795510812672)]} 0 3
[junit4:junit4]   2> 5423 T603 C77 P60514 /update {wt=javabin&version=2} {add=[98 (1412149795516055552)]} 0 0
[junit4:junit4]   2> 5428 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5429 T618 C78 P56045 /update {wt=javabin&version=2} {add=[98 (1412149795518152704)]} 0 4
[junit4:junit4]   2> 5431 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[96 (-1412149795524444160)]} 0 1
[junit4:junit4]   2> 5433 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 5436 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10093 (1412149795513958400)]} 0 15
[junit4:junit4]   2> 5436 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5437 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[96 (-1412149795526541312)]} 0 4
[junit4:junit4]   2> 5438 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10091 (-1412149795531784192)]} 0 0
[junit4:junit4]   2> 5439 T602 C77 P60514 /update {wt=javabin&version=2} {add=[99 (1412149795532832768)]} 0 1
[junit4:junit4]   2> 5442 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5442 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10091 (-1412149795533881344)]} 0 2
[junit4:junit4]   2> 5444 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5444 T617 C78 P56045 /update {wt=javabin&version=2} {add=[99 (1412149795534929920)]} 0 3
[junit4:junit4]   2> 5444 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10094 (1412149795538075648)]} 0 0
[junit4:junit4]   2> 5447 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[97 (-1412149795541221376)]} 0 0
[junit4:junit4]   2> 5449 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5450 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10094 (1412149795541221376)]} 0 3
[junit4:junit4]   2> 5452 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5453 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[97 (-1412149795543318528)]} 0 4
[junit4:junit4]   2> 5453 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10095 (1412149795546464256)]} 0 1
[junit4:junit4]   2> 5456 T602 C77 P60514 /update {wt=javabin&version=2} {add=[100 (1412149795549609984)]} 0 1
[junit4:junit4]   2> 5465 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5466 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10095 (1412149795549609984)]} 0 11
[junit4:junit4]   2> 5467 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5468 T618 C78 P56045 /update {wt=javabin&version=2} {add=[100 (1412149795560095744)]} 0 3
[junit4:junit4]   2> 5468 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10096 (1412149795563241472)]} 0 0
[junit4:junit4]   2> 5473 T603 C77 P60514 /update {wt=javabin&version=2} {add=[101 (1412149795568484352)]} 0 0
[junit4:junit4]   2> 5475 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5475 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10096 (1412149795568484352)]} 0 3
[junit4:junit4]   2> 5477 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10093 (-1412149795572678656)]} 0 0
[junit4:junit4]   2> 5478 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5478 T617 C78 P56045 /update {wt=javabin&version=2} {add=[101 (1412149795570581504)]} 0 3
[junit4:junit4]   2> 5480 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[98 (-1412149795575824384)]} 0 0
[junit4:junit4]   2> 5481 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5482 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10093 (-1412149795575824384)]} 0 3
[junit4:junit4]   2> 5484 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5485 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[98 (-1412149795577921536)]} 0 3
[junit4:junit4]   2> 5488 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10097 (1412149795580018688)]} 0 4
[junit4:junit4]   2> 5490 T602 C77 P60514 /update {wt=javabin&version=2} {add=[102 (1412149795585261568)]} 0 1
[junit4:junit4]   2> 5493 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5493 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10097 (1412149795586310144)]} 0 3
[junit4:junit4]   2> 5494 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5495 T618 C78 P56045 /update {wt=javabin&version=2} {add=[102 (1412149795588407296)]} 0 3
[junit4:junit4]   2> 5498 T603 C77 P60514 /update {wt=javabin&version=2} {add=[103 (1412149795593650176)]} 0 1
[junit4:junit4]   2> 5500 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10098 (1412149795591553024)]} 0 5
[junit4:junit4]   2> 5507 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5508 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10098 (1412149795598893056)]} 0 6
[junit4:junit4]   2> 5510 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10096 (-1412149795607281664)]} 0 0
[junit4:junit4]   2> 5514 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5515 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10096 (-1412149795610427392)]} 0 3
[junit4:junit4]   2> 5519 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10099 (1412149795615670272)]} 0 2
[junit4:junit4]   2> 5520 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=14 
[junit4:junit4]   2> 5525 T618 C78 P56045 /update {wt=javabin&version=2} {add=[103 (1412149795597844480)]} 0 25
[junit4:junit4]   2> 5528 T602 C77 P60514 /update {wt=javabin&version=2} {add=[104 (1412149795625107456)]} 0 1
[junit4:junit4]   2> 5530 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5531 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10099 (1412149795618816000)]} 0 10
[junit4:junit4]   2> 5533 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5534 T618 C78 P56045 /update {wt=javabin&version=2} {add=[104 (1412149795628253184)]} 0 4
[junit4:junit4]   2> 5534 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10097 (-1412149795632447488)]} 0 0
[junit4:junit4]   2> 5537 T603 C77 P60514 /update {wt=javabin&version=2} {add=[105 (1412149795634544640)]} 0 1
[junit4:junit4]   2> 5539 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5540 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10097 (-1412149795634544640)]} 0 4
[junit4:junit4]   2> 5542 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5542 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10100 (1412149795640836096)]} 0 0
[junit4:junit4]   2> 5543 T617 C78 P56045 /update {wt=javabin&version=2} {add=[105 (1412149795637690368)]} 0 4
[junit4:junit4]   2> 5545 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[100 (-1412149795643981824)]} 0 0
[junit4:junit4]   2> 5547 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5550 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10100 (1412149795643981824)]} 0 6
[junit4:junit4]   2> 5552 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5553 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10101 (1412149795651321856)]} 0 1
[junit4:junit4]   2> 5553 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[100 (-1412149795650273280)]} 0 2
[junit4:junit4]   2> 5556 T603 C77 P60514 /update {wt=javabin&version=2} {add=[106 (1412149795654467584)]} 0 1
[junit4:junit4]   2> 5558 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5558 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10101 (1412149795654467584)]} 0 3
[junit4:junit4]   2> 5560 T603 C77 P60514 /update {wt=javabin&version=2} {delete=[10098 (-1412149795659710464)]} 0 0
[junit4:junit4]   2> 5560 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5561 T618 C78 P56045 /update {wt=javabin&version=2} {add=[106 (1412149795657613312)]} 0 3
[junit4:junit4]   2> 5563 T603 C77 P60514 /update {wt=javabin&version=2} {add=[107 (1412149795662856192)]} 0 0
[junit4:junit4]   2> 5564 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5565 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[10098 (-1412149795661807616)]} 0 3
[junit4:junit4]   2> 5567 T603 C77 P60514 /update {wt=javabin&version=2} {add=[10102 (1412149795667050496)]} 0 0
[junit4:junit4]   2> 5570 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5571 T618 C78 P56045 /update {wt=javabin&version=2} {add=[107 (1412149795664953344)]} 0 6
[junit4:junit4]   2> 5572 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5572 T617 C78 P56045 /update {wt=javabin&version=2} {add=[10102 (1412149795670196224)]} 0 3
[junit4:junit4]   2> 5573 T603 C77 P60514 /update {wt=javabin&version=2} {add=[108 (1412149795673341952)]} 0 1
[junit4:junit4]   2> 5575 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10103 (1412149795675439104)]} 0 0
[junit4:junit4]   2> 5581 T628 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5582 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10103 (1412149795678584832)]} 0 4
[junit4:junit4]   2> 5584 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10104 (1412149795684876288)]} 0 0
[junit4:junit4]   2> 5586 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 5587 T617 C78 P56045 /update {wt=javabin&version=2} {add=[108 (1412149795676487680)]} 0 11
[junit4:junit4]   2> 5589 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[102 (-1412149795690119168)]} 0 0
[junit4:junit4]   2> 5590 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5590 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10104 (1412149795688022016)]} 0 4
[junit4:junit4]   2> 5593 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5593 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10105 (1412149795693264896)]} 0 1
[junit4:junit4]   2> 5593 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[102 (-1412149795692216320)]} 0 2
[junit4:junit4]   2> 5596 T602 C77 P60514 /update {wt=javabin&version=2} {add=[109 (1412149795696410624)]} 0 1
[junit4:junit4]   2> 5598 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5598 T618 C78 P56045 /update {wt=javabin&version=2} {add=[10105 (1412149795696410624)]} 0 3
[junit4:junit4]   2> 5600 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[10100 (-1412149795701653504)]} 0 0
[junit4:junit4]   2> 5601 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5601 T617 C78 P56045 /update {wt=javabin&version=2} {add=[109 (1412149795699556352)]} 0 3
[junit4:junit4]   2> 5603 T602 C77 P60514 /update {wt=javabin&version=2} {delete=[104 (-1412149795704799232)]} 0 0
[junit4:junit4]   2> 5604 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5604 T618 C78 P56045 /update {wt=javabin&version=2} {delete=[10100 (-1412149795703750656)]} 0 2
[junit4:junit4]   2> 5614 T602 C77 P60514 /update {wt=javabin&version=2} {add=[10106 (1412149795715284992)]} 0 1
[junit4:junit4]   2> 5615 T629 C76 P57288 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:56045/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5615 T617 C78 P56045 /update {wt=javabin&version=2} {delete=[104 (-1412149795715284992)]} 0 2
[junit4:junit4]   2> 5618 T602 C77 P60514 /update {wt=javabin&version=2} {add=[110 (1412149795720527872)]} 0 1
[junit4:junit4]   2> 5618 T629 C76 P57288 REQ /update {wt=

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

equired$1.evaluate(TestRuleAssertionsRequired.java:40)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 28360 T765 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:45903
[junit4:junit4]   2> 28460 T766 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 28460 T590 oaz.ZooKeeper.close Session: 0x1398f6c080d0007 closed
[junit4:junit4]   2> 28462 T590 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 28575 T590 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=C16BF03659D8C09 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fi -Dtests.timezone=GMT0 -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   28.6s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://localhost:57288/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([C16BF03659D8C09:8DF0311B12C2EC35]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:769)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:429)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 28616 T590 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {intDefault=Pulsing40(freqCutoff=19 minBlockSize=65 maxBlockSize=181), a_t=Lucene40(minBlockSize=65 maxBlockSize=181), other_tl1=Pulsing40(freqCutoff=19 minBlockSize=65 maxBlockSize=181), a_si=Pulsing40(freqCutoff=19 minBlockSize=65 maxBlockSize=181), range_facet_si=Lucene40(minBlockSize=65 maxBlockSize=181), id=Block(blocksize=128), text=PostingsFormat(name=Asserting), _version_=Pulsing40(freqCutoff=19 minBlockSize=65 maxBlockSize=181), range_facet_l=Lucene40(minBlockSize=65 maxBlockSize=181), rnd_b=Block(blocksize=128), timestamp=Lucene40(minBlockSize=65 maxBlockSize=181), range_facet_sl=Block(blocksize=128), multiDefault=Lucene40(minBlockSize=65 maxBlockSize=181)}, sim=DefaultSimilarity, locale=fi, timezone=GMT0
[junit4:junit4]   2> NOTE: Linux 3.2.0-29-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=40561664,total=89804800
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestDocSet, PeerSyncTest, TestStressReorder, TestPluginEnable, TimeZoneUtilsTest, PrimUtilsTest, TestPropInjectDefaults, IndexReaderFactoryTest, TestStressVersions, FieldMutatingUpdateProcessorTest, StatsComponentTest, AnalysisAfterCoreReloadTest, XsltUpdateRequestHandlerTest, UUIDFieldTest, TestFastWriter, TestWriterPerf, SuggesterTSTTest, TestMultiCoreConfBootstrap, TestUpdate, ZkNodePropsTest, XmlUpdateRequestHandlerTest, TestFunctionQuery, RAMDirectoryFactoryTest, TestReload, DistributedQueryElevationComponentTest, TestQuerySenderListener, TestBM25SimilarityFactory, IndexSchemaTest, EchoParamsTest, DirectUpdateHandlerTest, StatelessScriptUpdateProcessorFactoryTest, TestArbitraryIndexDir, TestSolrJ, TestDocumentBuilder, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 28.64s, 1 test, 1 error <<< FAILURES!

[...truncated 544 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:29: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:144: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:62: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1056: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:741: There were test failures: 228 suites, 917 tests, 1 error, 6 ignored (1 assumption)

Total time: 23 minutes 5 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b51 -client -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message