lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.7.0_07) - Build # 880 - Failure!
Date Wed, 05 Sep 2012 01:08:00 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/880/
Java: 32bit/jdk1.7.0_07 -server -XX:+UseSerialGC

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

Error Message:
Server at http://localhost:37287/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:37287/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([DA9F99713245DD6F:5B791769451ABD53]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java: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:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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 6871 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 3 T935 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-1346806955523
[junit4:junit4]   2> 4 T935 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 5 T936 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 6 T936 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 6 T936 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 105 T935 oasc.ZkTestServer.run start zk server on port:41963
[junit4:junit4]   2> 105 T935 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41963 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6e66b8
[junit4:junit4]   2> 106 T941 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41963
[junit4:junit4]   2> 106 T935 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T941 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41963, initiating session
[junit4:junit4]   2> 107 T937 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56954
[junit4:junit4]   2> 107 T937 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56954
[junit4:junit4]   2> 107 T939 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 226 T939 oazs.NIOServerCnxn.finishSessionInit Established session 0x13993f33e050000 with negotiated timeout 10000 for client /127.0.0.1:56954
[junit4:junit4]   2> 226 T941 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41963, sessionid = 0x13993f33e050000, negotiated timeout = 10000
[junit4:junit4]   2> 226 T942 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6e66b8 name:ZooKeeperConnection Watcher:127.0.0.1:41963 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 226 T935 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 227 T935 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 229 T940 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13993f33e050000
[junit4:junit4]   2> 230 T942 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 230 T935 oaz.ZooKeeper.close Session: 0x13993f33e050000 closed
[junit4:junit4]   2> 230 T937 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56954 which had sessionid 0x13993f33e050000
[junit4:junit4]   2> 230 T935 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41963/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1772339
[junit4:junit4]   2> 231 T943 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41963
[junit4:junit4]   2> 231 T935 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 231 T943 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41963, initiating session
[junit4:junit4]   2> 232 T937 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56955
[junit4:junit4]   2> 232 T937 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56955
[junit4:junit4]   2> 232 T939 oazs.NIOServerCnxn.finishSessionInit Established session 0x13993f33e050001 with negotiated timeout 10000 for client /127.0.0.1:56955
[junit4:junit4]   2> 232 T943 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41963, sessionid = 0x13993f33e050001, negotiated timeout = 10000
[junit4:junit4]   2> 233 T944 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1772339 name:ZooKeeperConnection Watcher:127.0.0.1:41963/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 233 T935 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 233 T935 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 235 T935 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 236 T935 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 236 T935 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 238 T935 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 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 241 T935 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> 241 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 243 T935 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> 243 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 245 T935 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> 245 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 246 T935 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> 247 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 249 T935 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> 250 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 252 T935 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> 253 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 255 T935 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 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 259 T935 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> 260 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 262 T935 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> 263 T935 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 264 T940 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13993f33e050001
[junit4:junit4]   2> 264 T944 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 264 T935 oaz.ZooKeeper.close Session: 0x13993f33e050001 closed
[junit4:junit4]   2> 264 T937 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56955 which had sessionid 0x13993f33e050001
[junit4:junit4]   2> 265 T935 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 268 T935 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:34041
[junit4:junit4]   2> 269 T935 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 269 T935 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> 270 T935 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> 287 T935 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 287 T935 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 288 T935 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> 288 T935 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> 288 T935 oasc.CoreContainer.<init> New CoreContainer 31197766
[junit4:junit4]   2> 289 T935 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> 289 T935 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> 305 T935 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 317 T935 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41963/solr
[junit4:junit4]   2> 318 T935 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41963/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@f01565
[junit4:junit4]   2> 319 T954 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41963
[junit4:junit4]   2> 319 T935 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 319 T954 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41963, initiating session
[junit4:junit4]   2> 320 T937 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56956
[junit4:junit4]   2> 320 T937 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56956
[junit4:junit4]   2> 321 T939 oazs.NIOServerCnxn.finishSessionInit Established session 0x13993f33e050002 with negotiated timeout 8000 for client /127.0.0.1:56956
[junit4:junit4]   2> 321 T954 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41963, sessionid = 0x13993f33e050002, negotiated timeout = 8000
[junit4:junit4]   2> 321 T955 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f01565 name:ZooKeeperConnection Watcher:127.0.0.1:41963/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 321 T935 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 323 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 323 T935 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 324 T935 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34041_solr
[junit4:junit4]   2> 325 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34041_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34041_solr
[junit4:junit4]   2> 325 T935 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34041_solr
[junit4:junit4]   2> 326 T935 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 327 T935 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 327 T935 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 327 T935 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 327 T935 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 328 T935 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 328 T935 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 328 T935 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 329 T935 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 329 T935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 332 T935 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 335 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 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> 335 T935 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 336 T935 oasc.Overseer.start Overseer (id=88264340637286402-127.0.0.1:34041_solr-n_0000000000) starting
[junit4:junit4]   2> 336 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 337 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 337 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 338 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 338 T957 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 339 T935 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 340 T935 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 340 T956 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 342 T935 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> 342 T935 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 343 T935 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 343 T935 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 344 T935 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> 345 T935 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> 345 T935 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> 368 T935 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 408 T935 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 409 T935 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 412 T935 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 728 T935 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 733 T935 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 735 T935 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 742 T935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 745 T935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 747 T935 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 748 T935 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 749 T935 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-1346806955523/control/data/
[junit4:junit4]   2> 749 T935 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2145e6
[junit4:junit4]   2> 755 T935 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1346806955523/control/data/index/
[junit4:junit4]   2> 755 T935 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1346806955523/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 756 T935 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-1346806955523/control/data/index forceNew:false
[junit4:junit4]   2> 759 T935 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-1346806955523/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a5032e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 759 T935 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 760 T935 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 762 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 762 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 762 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 763 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 763 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 763 T935 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 764 T935 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 764 T935 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 765 T935 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 765 T935 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 766 T935 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 766 T935 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 767 T935 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 767 T935 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 767 T935 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 768 T935 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 768 T935 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 768 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 769 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 769 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 770 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 770 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 771 T935 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 771 T935 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 772 T935 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 773 T935 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 774 T935 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 775 T935 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 777 T935 oass.SolrIndexSearcher.<init> Opening Searcher@9328d2 main
[junit4:junit4]   2> 777 T935 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 778 T935 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 778 T935 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 783 T935 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 803 T958 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9328d2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 809 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 810 T935 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 811 T935 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:34041/solr shardId:control_shard
[junit4:junit4]   2> 811 T935 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 814 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:delete cxid:0x5f 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> 814 T935 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 815 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x60 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 816 T935 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34041/solr/collection1/
[junit4:junit4]   2> 816 T935 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 818 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x69 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 842 T956 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 849 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050002 type:create cxid:0x71 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 854 T955 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 869 T935 oasc.ZkController.register We are http://127.0.0.1:34041/solr/collection1/ and leader is http://127.0.0.1:34041/solr/collection1/
[junit4:junit4]   2> 870 T935 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34041/solr
[junit4:junit4]   2> 870 T935 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 872 T935 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 873 T935 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 873 T935 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 874 T935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 879 T935 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 881 T935 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:58144
[junit4:junit4]   2> 882 T935 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 882 T935 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> 883 T935 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> 899 T935 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 899 T935 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 900 T935 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> 900 T935 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> 901 T935 oasc.CoreContainer.<init> New CoreContainer 18877027
[junit4:junit4]   2> 901 T935 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> 902 T935 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> 918 T935 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 928 T935 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41963/solr
[junit4:junit4]   2> 929 T935 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41963/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@8e1d84
[junit4:junit4]   2> 930 T968 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41963
[junit4:junit4]   2> 930 T935 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 930 T968 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41963, initiating session
[junit4:junit4]   2> 930 T937 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56958
[junit4:junit4]   2> 931 T937 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56958
[junit4:junit4]   2> 931 T939 oazs.NIOServerCnxn.finishSessionInit Established session 0x13993f33e050003 with negotiated timeout 8000 for client /127.0.0.1:56958
[junit4:junit4]   2> 931 T968 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41963, sessionid = 0x13993f33e050003, negotiated timeout = 8000
[junit4:junit4]   2> 931 T969 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8e1d84 name:ZooKeeperConnection Watcher:127.0.0.1:41963/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 932 T935 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 932 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 933 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 933 T935 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58144_solr
[junit4:junit4]   2> 934 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58144_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58144_solr
[junit4:junit4]   2> 934 T935 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58144_solr
[junit4:junit4]   2> 935 T955 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 935 T935 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 936 T935 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 936 T935 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 937 T935 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 937 T935 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 937 T935 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 938 T935 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 938 T935 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 939 T935 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 939 T935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 944 T935 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 948 T935 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> 949 T935 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 949 T935 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 950 T935 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 951 T935 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> 952 T935 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> 952 T935 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> 987 T935 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1026 T935 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1026 T935 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1030 T935 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1357 T956 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1359 T969 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1359 T955 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1386 T935 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1392 T935 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1394 T935 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1408 T935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1411 T935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1413 T935 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1414 T935 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1415 T935 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-1346806955523/jetty1/
[junit4:junit4]   2> 1415 T935 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2145e6
[junit4:junit4]   2> 1416 T935 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1346806955523/jetty1/index/
[junit4:junit4]   2> 1416 T935 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1346806955523/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1417 T935 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-1346806955523/jetty1/index forceNew:false
[junit4:junit4]   2> 1420 T935 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-1346806955523/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1bc9250; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1420 T935 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1421 T935 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1423 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1424 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1424 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1425 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1425 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1426 T935 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1427 T935 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1427 T935 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1428 T935 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1428 T935 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1429 T935 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1429 T935 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1430 T935 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1430 T935 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1431 T935 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1432 T935 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1432 T935 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1433 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1433 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1434 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1435 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1436 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1436 T935 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1437 T935 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1438 T935 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1439 T935 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1442 T935 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1443 T935 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1445 T935 oass.SolrIndexSearcher.<init> Opening Searcher@1ca3ef8 main
[junit4:junit4]   2> 1447 T935 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1447 T935 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1448 T935 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1453 T935 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1479 T970 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca3ef8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1861 T956 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1864 T955 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1864 T969 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1985 T935 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1986 T935 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:58144/solr shardId:shard1
[junit4:junit4]   2> 1986 T935 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 1990 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050003 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> 1990 T935 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 1991 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1991 T935 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58144/solr/collection1/
[junit4:junit4]   2> 1992 T935 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 1993 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2365 T956 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2370 T955 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2370 T969 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2397 T935 oasc.ZkController.register We are http://127.0.0.1:58144/solr/collection1/ and leader is http://127.0.0.1:58144/solr/collection1/
[junit4:junit4]   2> 2397 T935 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58144/solr
[junit4:junit4]   2> 2397 T935 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2398 T935 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2399 T935 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 2399 T935 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2400 T935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2404 T935 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2405 T935 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:37287
[junit4:junit4]   2> 2406 T935 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2406 T935 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> 2406 T935 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> 2417 T935 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2417 T935 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2418 T935 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> 2418 T935 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> 2418 T935 oasc.CoreContainer.<init> New CoreContainer 7966494
[junit4:junit4]   2> 2419 T935 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> 2419 T935 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> 2430 T935 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2437 T935 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41963/solr
[junit4:junit4]   2> 2437 T935 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41963/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@fbdd42
[junit4:junit4]   2> 2444 T980 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41963
[junit4:junit4]   2> 2438 T935 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2445 T980 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41963, initiating session
[junit4:junit4]   2> 2445 T937 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56959
[junit4:junit4]   2> 2445 T937 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56959
[junit4:junit4]   2> 2446 T939 oazs.NIOServerCnxn.finishSessionInit Established session 0x13993f33e050004 with negotiated timeout 8000 for client /127.0.0.1:56959
[junit4:junit4]   2> 2446 T980 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41963, sessionid = 0x13993f33e050004, negotiated timeout = 8000
[junit4:junit4]   2> 2446 T981 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@fbdd42 name:ZooKeeperConnection Watcher:127.0.0.1:41963/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2446 T935 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2447 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2448 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2448 T935 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37287_solr
[junit4:junit4]   2> 2449 T940 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13993f33e050004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37287_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37287_solr
[junit4:junit4]   2> 2449 T935 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37287_solr
[junit4:junit4]   2> 2450 T969 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2450 T955 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2450 T935 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2451 T935 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2451 T935 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2451 T935 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2451 T935 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2452 T935 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2452 T935 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2452 T935 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2453 T935 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2453 T935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2457 T935 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2459 T935 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> 2460 T935 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2460 T935 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2461 T935 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2461 T935 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> 2462 T935 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> 2462 T935 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> 2484 T935 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2523 T935 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2523 T935 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2527 T935 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2842 T935 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2846 T935 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2849 T935 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2856 T935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2858 T935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2861 T935 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2862 T935 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2863 T935 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-1346806955523/jetty2/
[junit4:junit4]   2> 2863 T935 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2145e6
[junit4:junit4]   2> 2864 T935 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1346806955523/jetty2/index/
[junit4:junit4]   2> 2864 T935 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1346806955523/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2865 T935 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-1346806955523/jetty2/index forceNew:false
[junit4:junit4]   2> 2868 T935 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-1346806955523/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c2fb4d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2868 T935 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2869 T935 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2870 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2871 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2871 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2871 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2872 T935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2872 T956 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2872 T935 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2873 T935 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2873 T935 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2874 T935 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2874 T935 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2875 T955 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2875 T969 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2875 T981 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2875 T935 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2876 T935 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2876 T935 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2877 T935 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2877 T935 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2877 T935 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2878 T935 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2878 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2879 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2880 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2880 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2881 T935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2882 T935 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2882 T935 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2883 T935 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2884 T935 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2885 T935 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2886 T935 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 2888 T935 oass.SolrIndexSearcher.<init> Opening Searcher@11c2e21 main
[junit4:junit4]   2> 2888 T935 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2889 T935 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2889 T935 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2893 T935 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2914 T982 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11c2e21 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3377 T956 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3379 T955 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3379 T969 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3379 T981 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3419 T935 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3419 T935 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:37287/solr shardId:shard1
[junit4:junit4]   2> 3422 T935 oasc.ZkController.register We are http://127.0.0.1:37287/solr/collection1/ and leader is http://127.0.0.1:58144/solr/collection1/
[junit4:junit4]   2> 3422 T935 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37287/solr
[junit4:junit4]   2> 3422 T935 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 3423 T935 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C85 name=collection1 org.apache.solr.core.SolrCore@1934d10 url=http://127.0.0.1:37287/solr/collection1 node=127.0.0.1:37287_solr
[junit4:junit4]   2> 3426 T983 C85 P37287 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 3426 T935 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3428 T983 C85 P37287 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 3428 T935 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3429 T983 C85 P37287 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3429 T935 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3431 T935 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3437 T935 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41963/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@88052c
[junit4:junit4]   2> 3438 T984 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41963
[junit4:junit4]   2> 3438 T935 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3438 T984 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41963, initiating session
[junit4:junit4]   2> 3438 T937 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56962
[junit4:junit4]   2> 3438 T937 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56962
[junit4:junit4]   2> 3439 T939 oazs.NIOServerCnxn.finishSessionInit Established session 0x13993f33e050005 with negotiated timeout 10000 for client /127.0.0.1:56962
[junit4:junit4]   2> 3439 T984 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41963, sessionid = 0x13993f33e050005, negotiated timeout = 10000
[junit4:junit4]   2> 3439 T985 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@88052c name:ZooKeeperConnection Watcher:127.0.0.1:41963/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3439 T935 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3440 T935 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3442 T935 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:false
[junit4:junit4]   2> 3443 T935 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3448 T935 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C86 name=collection1 org.apache.solr.core.SolrCore@12669e0 url=http://127.0.0.1:34041/solr/collection1 node=127.0.0.1:34041_solr
[junit4:junit4]   2> 3466 T948 C86 P34041 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-1346806955523/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a5032e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3466 T948 C86 P34041 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3470 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10001 (1412229453822558208)]} 0 7
[junit4:junit4]   2> 3472 T987 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41963/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@152cdd5
[junit4:junit4]   2> 3472 T987 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3475 T988 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41963
[junit4:junit4]   2> 3475 T947 C86 P34041 /update {wt=javabin&version=2} {add=[1 (1412229453822558209)]} 0 11
[junit4:junit4]   2> 3476 T937 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:56965
[junit4:junit4]   2> 3476 T988 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41963, initiating session
[junit4:junit4]   2> 3476 T937 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:56965
[junit4:junit4]   2> 3477 T939 oazs.NIOServerCnxn.finishSessionInit Established session 0x13993f33e050006 with negotiated timeout 10000 for client /127.0.0.1:56965
[junit4:junit4]   2> 3477 T988 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41963, sessionid = 0x13993f33e050006, negotiated timeout = 10000
[junit4:junit4]   2> 3478 T989 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@152cdd5 name:ZooKeeperConnection Watcher:127.0.0.1:41963/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3478 T987 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3478 T987 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C87 name=collection1 org.apache.solr.core.SolrCore@574f9f url=http://127.0.0.1:58144/solr/collection1 node=127.0.0.1:58144_solr
[junit4:junit4]   2> 3487 T962 C87 P58144 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-1346806955523/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1bc9250; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3488 T962 C87 P58144 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3491 T962 C87 P58144 /update {wt=javabin&version=2} {add=[1 (1412229453845626880)]} 0 6
[junit4:junit4]   2> 3492 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10001 (1412229453847724032)]} 0 5
[junit4:junit4]   2> 3495 T947 C86 P34041 /update {wt=javabin&version=2} {add=[2 (1412229453854015488)]} 0 1
[junit4:junit4]   2> 3496 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10002 (1412229453855064064)]} 0 1
[junit4:junit4]   2> 3499 T963 C87 P58144 /update {wt=javabin&version=2} {add=[2 (1412229453858209792)]} 0 1
[junit4:junit4]   2> 3500 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10002 (1412229453859258368)]} 0 1
[junit4:junit4]   2> 3503 T948 C86 P34041 /update {wt=javabin&version=2} {add=[3 (1412229453862404096)]} 0 1
[junit4:junit4]   2> 3504 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10003 (1412229453863452672)]} 0 2
[junit4:junit4]   2> 3507 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10003 (1412229453866598400)]} 0 1
[junit4:junit4]   2> 3508 T963 C87 P58144 /update {wt=javabin&version=2} {add=[3 (1412229453866598401)]} 0 2
[junit4:junit4]   2> 3510 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10003 (-1412229453870792704)]} 0 0
[junit4:junit4]   2> 3511 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[3 (-1412229453871841280)]} 0 1
[junit4:junit4]   2> 3514 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[3 (-1412229453874987008)]} 0 1
[junit4:junit4]   2> 3514 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10003 (-1412229453874987009)]} 0 1
[junit4:junit4]   2> 3518 T948 C86 P34041 /update {wt=javabin&version=2} {add=[4 (1412229453878132736)]} 0 1
[junit4:junit4]   2> 3518 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10004 (1412229453878132737)]} 0 1
[junit4:junit4]   2> 3521 T963 C87 P58144 /update {wt=javabin&version=2} {add=[4 (1412229453882327040)]} 0 1
[junit4:junit4]   2> 3522 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10004 (1412229453882327041)]} 0 1
[junit4:junit4]   2> 3525 T947 C86 P34041 /update {wt=javabin&version=2} {add=[5 (1412229453885472768)]} 0 1
[junit4:junit4]   2> 3525 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10005 (1412229453885472769)]} 0 1
[junit4:junit4]   2> 3529 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10005 (1412229453889667072)]} 0 1
[junit4:junit4]   2> 3532 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10006 (1412229453893861376)]} 0 1
[junit4:junit4]   2> 3534 T962 C87 P58144 /update {wt=javabin&version=2} {add=[5 (1412229453889667073)]} 0 6
[junit4:junit4]   2> 3538 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10006 (1412229453898055680)]} 0 3
[junit4:junit4]   2> 3539 T948 C86 P34041 /update {wt=javabin&version=2} {add=[6 (1412229453899104256)]} 0 2
[junit4:junit4]   2> 3542 T963 C87 P58144 /update {wt=javabin&version=2} {add=[6 (1412229453903298560)]} 0 1
[junit4:junit4]   2> 3542 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10007 (1412229453903298560)]} 0 1
[junit4:junit4]   2> 3546 T948 C86 P34041 /update {wt=javabin&version=2} {add=[7 (1412229453907492864)]} 0 1
[junit4:junit4]   2> 3546 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10007 (1412229453907492864)]} 0 1
[junit4:junit4]   2> 3550 T962 C87 P58144 /update {wt=javabin&version=2} {add=[7 (1412229453911687168)]} 0 2
[junit4:junit4]   2> 3554 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10008 (1412229453911687168)]} 0 6
[junit4:junit4]   2> 3555 T947 C86 P34041 /update {wt=javabin&version=2} {add=[8 (1412229453916930048)]} 0 2
[junit4:junit4]   2> 3558 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10008 (1412229453919027200)]} 0 2
[junit4:junit4]   2> 3558 T963 C87 P58144 /update {wt=javabin&version=2} {add=[8 (1412229453920075776)]} 0 1
[junit4:junit4]   2> 3562 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10009 (1412229453924270080)]} 0 2
[junit4:junit4]   2> 3563 T948 C86 P34041 /update {wt=javabin&version=2} {add=[9 (1412229453924270081)]} 0 2
[junit4:junit4]   2> 3567 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10009 (1412229453928464384)]} 0 2
[junit4:junit4]   2> 3569 T962 C87 P58144 /update {wt=javabin&version=2} {add=[9 (1412229453929512960)]} 0 3
[junit4:junit4]   2> 3571 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10010 (1412229453933707264)]} 0 1
[junit4:junit4]   2> 3574 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10 (1412229453936852992)]} 0 2
[junit4:junit4]   2> 3576 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10010 (1412229453937901568)]} 0 2
[junit4:junit4]   2> 3579 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10011 (1412229453942095872)]} 0 1
[junit4:junit4]   2> 3585 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10 (1412229453941047296)]} 0 8
[junit4:junit4]   2> 3587 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10011 (1412229453948387328)]} 0 5
[junit4:junit4]   2> 3590 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10008 (-1412229453954678784)]} 0 0
[junit4:junit4]   2> 3594 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10008 (-1412229453958873088)]} 0 1
[junit4:junit4]   2> 3594 T947 C86 P34041 /update {wt=javabin&version=2} {add=[11 (1412229453952581632)]} 0 6
[junit4:junit4]   2> 3599 T962 C87 P58144 /update {wt=javabin&version=2} {add=[11 (1412229453962018816)]} 0 2
[junit4:junit4]   2> 3599 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10012 (1412229453962018816)]} 0 2
[junit4:junit4]   2> 3602 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[8 (-1412229453967261696)]} 0 1
[junit4:junit4]   2> 3605 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10012 (1412229453969358848)]} 0 1
[junit4:junit4]   2> 3605 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[8 (-1412229453970407424)]} 0 0
[junit4:junit4]   2> 3609 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10009 (-1412229453974601728)]} 0 1
[junit4:junit4]   2> 3610 T947 C86 P34041 /update {wt=javabin&version=2} {add=[12 (1412229453974601729)]} 0 2
[junit4:junit4]   2> 3613 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10009 (-1412229453978796032)]} 0 1
[junit4:junit4]   2> 3614 T962 C87 P58144 /update {wt=javabin&version=2} {add=[12 (1412229453978796033)]} 0 1
[junit4:junit4]   2> 3617 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10013 (1412229453981941760)]} 0 2
[junit4:junit4]   2> 3618 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[9 (-1412229453982990336)]} 0 1
[junit4:junit4]   2> 3621 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[9 (-1412229453987184640)]} 0 1
[junit4:junit4]   2> 3622 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10013 (1412229453986136064)]} 0 2
[junit4:junit4]   2> 3626 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10014 (1412229453991378944)]} 0 2
[junit4:junit4]   2> 3626 T948 C86 P34041 /update {wt=javabin&version=2} {add=[13 (1412229453990330368)]} 0 2
[junit4:junit4]   2> 3631 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10014 (1412229453995573248)]} 0 2
[junit4:junit4]   2> 3632 T963 C87 P58144 /update {wt=javabin&version=2} {add=[13 (1412229453995573249)]} 0 3
[junit4:junit4]   2> 3634 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10010 (-1412229454000816128)]} 0 0
[junit4:junit4]   2> 3636 T947 C86 P34041 /update {wt=javabin&version=2} {add=[14 (1412229454000816129)]} 0 2
[junit4:junit4]   2> 3640 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10010 (-1412229454007107584)]} 0 3
[junit4:junit4]   2> 3640 T962 C87 P58144 /update {wt=javabin&version=2} {add=[14 (1412229454006059008)]} 0 2
[junit4:junit4]   2> 3643 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10 (-1412229454010253312)]} 0 0
[junit4:junit4]   2> 3644 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10015 (1412229454010253313)]} 0 1
[junit4:junit4]   2> 3646 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10 (-1412229454013399040)]} 0 0
[junit4:junit4]   2> 3647 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10015 (1412229454014447616)]} 0 1
[junit4:junit4]   2> 3650 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10011 (-1412229454017593344)]} 0 0
[junit4:junit4]   2> 3651 T947 C86 P34041 /update {wt=javabin&version=2} {add=[15 (1412229454016544768)]} 0 2
[junit4:junit4]   2> 3656 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10011 (-1412229454023884800)]} 0 3
[junit4:junit4]   2> 3659 T962 C87 P58144 /update {wt=javabin&version=2} {add=[15 (1412229454020739072)]} 0 6
[junit4:junit4]   2> 3660 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10016 (1412229454027030528)]} 0 1
[junit4:junit4]   2> 3662 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[11 (-1412229454030176256)]} 0 1
[junit4:junit4]   2> 3665 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[11 (-1412229454033321984)]} 0 1
[junit4:junit4]   2> 3665 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10016 (1412229454031224832)]} 0 3
[junit4:junit4]   2> 3669 T948 C86 P34041 /update {wt=javabin&version=2} {add=[16 (1412229454036467713)]} 0 1
[junit4:junit4]   2> 3670 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10017 (1412229454036467712)]} 0 2
[junit4:junit4]   2> 3673 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10017 (1412229454040662016)]} 0 1
[junit4:junit4]   2> 3674 T962 C87 P58144 /update {wt=javabin&version=2} {add=[16 (1412229454040662017)]} 0 2
[junit4:junit4]   2> 3680 T948 C86 P34041 /update {wt=javabin&version=2} {add=[17 (1412229454045904896)]} 0 4
[junit4:junit4]   2> 3681 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10018 (1412229454044856320)]} 0 5
[junit4:junit4]   2> 3685 T962 C87 P58144 /update {wt=javabin&version=2} {add=[17 (1412229454053244928)]} 0 2
[junit4:junit4]   2> 3685 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10018 (1412229454053244929)]} 0 2
[junit4:junit4]   2> 3689 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10019 (1412229454057439232)]} 0 1
[junit4:junit4]   2> 3690 T947 C86 P34041 /update {wt=javabin&version=2} {add=[18 (1412229454057439233)]} 0 2
[junit4:junit4]   2> 3693 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10019 (1412229454061633536)]} 0 2
[junit4:junit4]   2> 3694 T962 C87 P58144 /update {wt=javabin&version=2} {add=[18 (1412229454062682112)]} 0 2
[junit4:junit4]   2> 3696 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10013 (-1412229454064779264)]} 0 1
[junit4:junit4]   2> 3697 T948 C86 P34041 /update {wt=javabin&version=2} {add=[19 (1412229454065827840)]} 0 1
[junit4:junit4]   2> 3698 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10013 (-1412229454067924992)]} 0 0
[junit4:junit4]   2> 3701 T963 C87 P58144 /update {wt=javabin&version=2} {add=[19 (1412229454070022144)]} 0 1
[junit4:junit4]   2> 3702 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10020 (1412229454071070720)]} 0 1
[junit4:junit4]   2> 3705 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[13 (-1412229454074216448)]} 0 1
[junit4:junit4]   2> 3706 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10020 (1412229454075265024)]} 0 1
[junit4:junit4]   2> 3708 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[13 (-1412229454078410752)]} 0 1
[junit4:junit4]   2> 3710 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10021 (1412229454079459328)]} 0 2
[junit4:junit4]   2> 3711 T948 C86 P34041 /update {wt=javabin&version=2} {add=[20 (1412229454080507904)]} 0 1
[junit4:junit4]   2> 3715 T963 C87 P58144 /update {wt=javabin&version=2} {add=[20 (1412229454083653633)]} 0 2
[junit4:junit4]   2> 3718 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10021 (1412229454083653632)]} 0 6
[junit4:junit4]   2> 3722 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10022 (1412229454092042240)]} 0 2
[junit4:junit4]   2> 3722 T948 C86 P34041 /update {wt=javabin&version=2} {add=[21 (1412229454088896512)]} 0 4
[junit4:junit4]   2> 3726 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10022 (1412229454096236544)]} 0 1
[junit4:junit4]   2> 3727 T962 C87 P58144 /update {wt=javabin&version=2} {add=[21 (1412229454096236545)]} 0 2
[junit4:junit4]   2> 3729 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10014 (-1412229454100430848)]} 0 1
[junit4:junit4]   2> 3731 T947 C86 P34041 /update {wt=javabin&version=2} {add=[22 (1412229454101479424)]} 0 2
[junit4:junit4]   2> 3732 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10014 (-1412229454103576576)]} 0 0
[junit4:junit4]   2> 3734 T963 C87 P58144 /update {wt=javabin&version=2} {add=[22 (1412229454104625152)]} 0 1
[junit4:junit4]   2> 3736 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10023 (1412229454106722304)]} 0 2
[junit4:junit4]   2> 3737 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[14 (-1412229454108819456)]} 0 0
[junit4:junit4]   2> 3739 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10023 (1412229454109868032)]} 0 1
[junit4:junit4]   2> 3739 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[14 (-1412229454110916608)]} 0 0
[junit4:junit4]   2> 3742 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10015 (-1412229454114062336)]} 0 0
[junit4:junit4]   2> 3743 T947 C86 P34041 /update {wt=javabin&version=2} {add=[23 (1412229454114062337)]} 0 1
[junit4:junit4]   2> 3745 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10015 (-1412229454117208064)]} 0 0
[junit4:junit4]   2> 3753 T963 C87 P58144 /update {wt=javabin&version=2} {add=[23 (1412229454124548096)]} 0 7
[junit4:junit4]   2> 3755 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10024 (1412229454126645248)]} 0 1
[junit4:junit4]   2> 3757 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[15 (-1412229454129790976)]} 0 1
[junit4:junit4]   2> 3759 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10024 (1412229454130839552)]} 0 2
[junit4:junit4]   2> 3759 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[15 (-1412229454131888128)]} 0 0
[junit4:junit4]   2> 3764 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10025 (1412229454136082432)]} 0 2
[junit4:junit4]   2> 3765 T948 C86 P34041 /update {wt=javabin&version=2} {add=[24 (1412229454135033856)]} 0 3
[junit4:junit4]   2> 3768 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10025 (1412229454140276736)]} 0 1
[junit4:junit4]   2> 3769 T962 C87 P58144 /update {wt=javabin&version=2} {add=[24 (1412229454140276737)]} 0 2
[junit4:junit4]   2> 3771 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10016 (-1412229454144471040)]} 0 0
[junit4:junit4]   2> 3773 T947 C86 P34041 /update {wt=javabin&version=2} {add=[25 (1412229454145519616)]} 0 2
[junit4:junit4]   2> 3774 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10016 (-1412229454147616768)]} 0 0
[junit4:junit4]   2> 3780 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10026 (1412229454149713920)]} 0 4
[junit4:junit4]   2> 3781 T963 C87 P58144 /update {wt=javabin&version=2} {add=[25 (1412229454148665344)]} 0 6
[junit4:junit4]   2> 3784 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[16 (-1412229454158102528)]} 0 0
[junit4:junit4]   2> 3785 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10026 (1412229454157053952)]} 0 2
[junit4:junit4]   2> 3787 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[16 (-1412229454161248256)]} 0 0
[junit4:junit4]   2> 3790 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10027 (1412229454162296832)]} 0 3
[junit4:junit4]   2> 3791 T948 C86 P34041 /update {wt=javabin&version=2} {add=[26 (1412229454164393984)]} 0 1
[junit4:junit4]   2> 3794 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10027 (1412229454167539712)]} 0 2
[junit4:junit4]   2> 3795 T962 C87 P58144 /update {wt=javabin&version=2} {add=[26 (1412229454167539713)]} 0 2
[junit4:junit4]   2> 3797 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10017 (-1412229454171734016)]} 0 0
[junit4:junit4]   2> 3798 T947 C86 P34041 /update {wt=javabin&version=2} {add=[27 (1412229454171734017)]} 0 1
[junit4:junit4]   2> 3800 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10017 (-1412229454174879744)]} 0 0
[junit4:junit4]   2> 3802 T963 C87 P58144 /update {wt=javabin&version=2} {add=[27 (1412229454175928320)]} 0 1
[junit4:junit4]   2> 3803 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10028 (1412229454178025472)]} 0 1
[junit4:junit4]   2> 3805 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[17 (-1412229454180122624)]} 0 1
[junit4:junit4]   2> 3807 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10028 (1412229454181171200)]} 0 1
[junit4:junit4]   2> 3807 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[17 (-1412229454182219776)]} 0 0
[junit4:junit4]   2> 3809 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10018 (-1412229454184316928)]} 0 0
[junit4:junit4]   2> 3811 T947 C86 P34041 /update {wt=javabin&version=2} {add=[28 (1412229454185365504)]} 0 1
[junit4:junit4]   2> 3812 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10018 (-1412229454187462656)]} 0 1
[junit4:junit4]   2> 3814 T963 C87 P58144 /update {wt=javabin&version=2} {add=[28 (1412229454188511232)]} 0 1
[junit4:junit4]   2> 3816 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10029 (1412229454190608384)]} 0 2
[junit4:junit4]   2> 3817 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[18 (-1412229454192705536)]} 0 0
[junit4:junit4]   2> 3819 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10029 (1412229454193754112)]} 0 1
[junit4:junit4]   2> 3820 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[18 (-1412229454195851264)]} 0 0
[junit4:junit4]   2> 3822 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10021 (-1412229454197948416)]} 0 1
[junit4:junit4]   2> 3823 T947 C86 P34041 /update {wt=javabin&version=2} {add=[29 (1412229454198996992)]} 0 1
[junit4:junit4]   2> 3824 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10021 (-1412229454200045568)]} 0 0
[junit4:junit4]   2> 3827 T963 C87 P58144 /update {wt=javabin&version=2} {add=[29 (1412229454202142720)]} 0 1
[junit4:junit4]   2> 3828 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10030 (1412229454203191296)]} 0 1
[junit4:junit4]   2> 3830 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[21 (-1412229454206337024)]} 0 0
[junit4:junit4]   2> 3832 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10030 (1412229454207385600)]} 0 1
[junit4:junit4]   2> 3833 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[21 (-1412229454208434176)]} 0 1
[junit4:junit4]   2> 3834 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10022 (-1412229454210531328)]} 0 0
[junit4:junit4]   2> 3836 T947 C86 P34041 /update {wt=javabin&version=2} {add=[30 (1412229454211579904)]} 0 1
[junit4:junit4]   2> 3838 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10022 (-1412229454214725632)]} 0 1
[junit4:junit4]   2> 3842 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10031 (1412229454217871360)]} 0 2
[junit4:junit4]   2> 3844 T963 C87 P58144 /update {wt=javabin&version=2} {add=[30 (1412229454215774208)]} 0 6
[junit4:junit4]   2> 3846 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[22 (-1412229454223114240)]} 0 0
[junit4:junit4]   2> 3847 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10031 (1412229454222065664)]} 0 2
[junit4:junit4]   2> 3849 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[22 (-1412229454226259968)]} 0 1
[junit4:junit4]   2> 3850 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10026 (-1412229454227308544)]} 0 1
[junit4:junit4]   2> 3853 T948 C86 P34041 /update {wt=javabin&version=2} {add=[31 (1412229454228357120)]} 0 2
[junit4:junit4]   2> 3852 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10026 (-1412229454229405696)]} 0 0
[junit4:junit4]   2> 3857 T962 C87 P58144 /update {wt=javabin&version=2} {add=[31 (1412229454233600000)]} 0 2
[junit4:junit4]   2> 3858 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10032 (1412229454234648576)]} 0 1
[junit4:junit4]   2> 3860 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[26 (-1412229454237794304)]} 0 1
[junit4:junit4]   2> 3862 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10032 (1412229454238842880)]} 0 2
[junit4:junit4]   2> 3863 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[26 (-1412229454240940032)]} 0 1
[junit4:junit4]   2> 3867 T948 C86 P34041 /update {wt=javabin&version=2} {add=[32 (1412229454244085760)]} 0 2
[junit4:junit4]   2> 3871 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10033 (1412229454243037184)]} 0 6
[junit4:junit4]   2> 3871 T963 C87 P58144 /update {wt=javabin&version=2} {add=[32 (1412229454248280064)]} 0 2
[junit4:junit4]   2> 3875 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10033 (1412229454252474368)]} 0 2
[junit4:junit4]   2> 3875 T947 C86 P34041 /update {wt=javabin&version=2} {add=[33 (1412229454252474368)]} 0 2
[junit4:junit4]   2> 3879 T963 C87 P58144 /update {wt=javabin&version=2} {add=[33 (1412229454256668672)]} 0 2
[junit4:junit4]   2> 3879 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10034 (1412229454256668672)]} 0 2
[junit4:junit4]   2> 3881 T956 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3883 T948 C86 P34041 /update {wt=javabin&version=2} {add=[34 (1412229454260862976)]} 0 2
[junit4:junit4]   2> 3883 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10034 (1412229454260862976)]} 0 2
[junit4:junit4]   2> 3884 T989 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3884 T985 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3884 T969 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3884 T955 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3884 T981 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3888 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10028 (-1412229454267154432)]} 0 2
[junit4:junit4]   2>  C85_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:37287_solr, base_url=http://127.0.0.1:37287/solr}
[junit4:junit4]   2> 3905 T974 C85 P37287 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-1346806955523/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c2fb4d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3905 T974 C85 P37287 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3906 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 3907 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10028 (-1412229454270300160)]} 0 16
[junit4:junit4]   2> 3910 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 3911 T963 C87 P58144 /update {wt=javabin&version=2} {add=[34 (1412229454269251584)]} 0 25
[junit4:junit4]   2> 3912 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10035 (1412229454290223104)]} 0 2
[junit4:junit4]   2> 3914 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[28 (-1412229454293368832)]} 0 1
[junit4:junit4]   2> 3918 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3919 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3919 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10035 (1412229454294417408)]} 0 5
[junit4:junit4]   2> 3919 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[28 (-1412229454296514560)]} 0 3
[junit4:junit4]   2> 3923 T948 C86 P34041 /update {wt=javabin&version=2} {add=[35 (1412229454302806016)]} 0 2
[junit4:junit4]   2> 3923 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10036 (1412229454302806017)]} 0 2
[junit4:junit4]   2> 3932 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 3932 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10036 (1412229454305951744)]} 0 7
[junit4:junit4]   2> 3934 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3935 T962 C87 P58144 /update {wt=javabin&version=2} {add=[35 (1412229454305951745)]} 0 10
[junit4:junit4]   2> 3935 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10029 (-1412229454316437504)]} 0 0
[junit4:junit4]   2> 3938 T948 C86 P34041 /update {wt=javabin&version=2} {add=[36 (1412229454318534656)]} 0 1
[junit4:junit4]   2> 3940 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3941 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10029 (-1412229454319583232)]} 0 4
[junit4:junit4]   2> 3945 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3946 T963 C87 P58144 /update {wt=javabin&version=2} {add=[36 (1412229454322728960)]} 0 6
[junit4:junit4]   2> 3948 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[29 (-1412229454330068992)]} 0 0
[junit4:junit4]   2> 3949 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10037 (1412229454325874688)]} 0 6
[junit4:junit4]   2> 3953 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3954 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[29 (-1412229454333214720)]} 0 3
[junit4:junit4]   2> 3956 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3956 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10037 (1412229454334263296)]} 0 5
[junit4:junit4]   2> 3958 T948 C86 P34041 /update {wt=javabin&version=2} {add=[37 (1412229454339506176)]} 0 2
[junit4:junit4]   2> 3959 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10030 (-1412229454341603328)]} 0 0
[junit4:junit4]   2> 3963 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3964 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10030 (-1412229454343700481)]} 0 3
[junit4:junit4]   2> 3965 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3966 T962 C87 P58144 /update {wt=javabin&version=2} {add=[37 (1412229454343700480)]} 0 6
[junit4:junit4]   2> 3968 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10038 (1412229454349991936)]} 0 1
[junit4:junit4]   2> 3969 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[30 (-1412229454352089088)]} 0 0
[junit4:junit4]   2> 3975 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3976 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[30 (-1412229454355234816)]} 0 4
[junit4:junit4]   2> 3977 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3978 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10038 (1412229454356283392)]} 0 7
[junit4:junit4]   2> 3980 T948 C86 P34041 /update {wt=javabin&version=2} {add=[38 (1412229454362574848)]} 0 1
[junit4:junit4]   2> 3987 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10039 (1412229454367817728)]} 0 4
[junit4:junit4]   2> 3990 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 3991 T963 C87 P58144 /update {wt=javabin&version=2} {add=[38 (1412229454368866304)]} 0 6
[junit4:junit4]   2> 3996 T948 C86 P34041 /update {wt=javabin&version=2} {add=[39 (1412229454379352064)]} 0 1
[junit4:junit4]   2> 4001 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 4002 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10039 (1412229454374109184)]} 0 12
[junit4:junit4]   2> 4008 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10040 (1412229454391934976)]} 0 2
[junit4:junit4]   2> 4009 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4009 T963 C87 P58144 /update {wt=javabin&version=2} {add=[39 (1412229454384594944)]} 0 9
[junit4:junit4]   2> 4013 T948 C86 P34041 /update {wt=javabin&version=2} {add=[40 (1412229454397177856)]} 0 1
[junit4:junit4]   2> 4017 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4018 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10040 (1412229454397177856)]} 0 7
[junit4:junit4]   2> 4022 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10041 (1412229454406615040)]} 0 2
[junit4:junit4]   2> 4031 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4032 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10041 (1412229454410809344)]} 0 7
[junit4:junit4]   2> 4037 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10042 (1412229454422343680)]} 0 2
[junit4:junit4]   2> 4037 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4038 T963 C87 P58144 /update {wt=javabin&version=2} {add=[40 (1412229454402420736)]} 0 22
[junit4:junit4]   2> 4042 T948 C86 P34041 /update {wt=javabin&version=2} {add=[41 (1412229454427586560)]} 0 2
[junit4:junit4]   2> 4045 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4046 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10042 (1412229454426537984)]} 0 6
[junit4:junit4]   2> 4059 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10032 (-1412229454445412352)]} 0 1
[junit4:junit4]   2> 4065 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 4066 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4066 T963 C87 P58144 /update {wt=javabin&version=2} {add=[41 (1412229454445412352)]} 0 19
[junit4:junit4]   2> 4067 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10032 (-1412229454448558080)]} 0 6
[junit4:junit4]   2> 4073 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10043 (1412229454460092416)]} 0 2
[junit4:junit4]   2> 4081 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4082 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10043 (1412229454464286720)]} 0 6
[junit4:junit4]   2> 4086 T948 C86 P34041 /update {wt=javabin&version=2} {add=[42 (1412229454456946688)]} 0 17
[junit4:junit4]   2> 4086 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10034 (-1412229454474772480)]} 0 1
[junit4:junit4]   2> 4093 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4094 T962 C87 P58144 /update {wt=javabin&version=2} {add=[42 (1412229454477918209)]} 0 6
[junit4:junit4]   2> 4098 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4099 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10034 (-1412229454477918208)]} 0 11
[junit4:junit4]   2> 4100 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[32 (-1412229454489452544)]} 0 3
[junit4:junit4]   2> 4104 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10044 (1412229454491549696)]} 0 2
[junit4:junit4]   2> 4105 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4107 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[32 (-1412229454492598272)]} 0 4
[junit4:junit4]   2> 4111 T948 C86 P34041 /update {wt=javabin&version=2} {add=[43 (1412229454498889728)]} 0 2
[junit4:junit4]   2> 4115 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4116 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10044 (1412229454498889728)]} 0 7
[junit4:junit4]   2> 4120 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10037 (-1412229454509375488)]} 0 1
[junit4:junit4]   2> 4124 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 4124 T963 C87 P58144 /update {wt=javabin&version=2} {add=[43 (1412229454504132608)]} 0 11
[junit4:junit4]   2> 4126 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4127 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10037 (-1412229454513569792)]} 0 5
[junit4:junit4]   2> 4127 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[34 (-1412229454517764096)]} 0 0
[junit4:junit4]   2> 4132 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10045 (1412229454520909824)]} 0 2
[junit4:junit4]   2> 4133 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4134 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[34 (-1412229454520909824)]} 0 4
[junit4:junit4]   2> 4138 T947 C86 P34041 /update {wt=javabin&version=2} {add=[44 (1412229454528249856)]} 0 2
[junit4:junit4]   2> 4140 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4141 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10045 (1412229454526152704)]} 0 6
[junit4:junit4]   2> 4144 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10038 (-1412229454535589888)]} 0 0
[junit4:junit4]   2> 4147 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4150 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4151 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10038 (-1412229454538735616)]} 0 4
[junit4:junit4]   2> 4151 T962 C87 P58144 /update {wt=javabin&version=2} {add=[44 (1412229454533492736)]} 0 10
[junit4:junit4]   2> 4154 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[37 (-1412229454545027072)]} 0 1
[junit4:junit4]   2> 4155 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10046 (1412229454545027073)]} 0 2
[junit4:junit4]   2> 4159 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4160 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[37 (-1412229454549221376)]} 0 4
[junit4:junit4]   2> 4167 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4167 T947 C86 P34041 /update {wt=javabin&version=2} {add=[45 (1412229454556561408)]} 0 4
[junit4:junit4]   2> 4168 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10046 (1412229454549221377)]} 0 11
[junit4:junit4]   2> 4182 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4182 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10047 (1412229454569144320)]} 0 11
[junit4:junit4]   2> 4183 T962 C87 P58144 /update {wt=javabin&version=2} {add=[45 (1412229454563901440)]} 0 13
[junit4:junit4]   2> 4186 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[38 (-1412229454579630080)]} 0 1
[junit4:junit4]   2> 4192 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4193 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[38 (-1412229454582775808)]} 0 5
[junit4:junit4]   2> 4194 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4195 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10047 (1412229454578581504)]} 0 10
[junit4:junit4]   2> 4199 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10048 (1412229454592212992)]} 0 2
[junit4:junit4]   2> 4202 T947 C86 P34041 /update {wt=javabin&version=2} {add=[46 (1412229454590115840)]} 0 6
[junit4:junit4]   2> 4209 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4210 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10048 (1412229454598504448)]} 0 6
[junit4:junit4]   2> 4214 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10049 (1412229454607941632)]} 0 1
[junit4:junit4]   2> 4218 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4219 T962 C87 P58144 /update {wt=javabin&version=2} {add=[46 (1412229454601650176)]} 0 13
[junit4:junit4]   2> 4222 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4224 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10049 (1412229454612135936)]} 0 7
[junit4:junit4]   2> 4227 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10050 (1412229454621573120)]} 0 1
[junit4:junit4]   2> 4230 T947 C86 P34041 /update {wt=javabin&version=2} {add=[47 (1412229454620524544)]} 0 5
[junit4:junit4]   2> 4235 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4237 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10050 (1412229454626816000)]} 0 7
[junit4:junit4]   2> 4240 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10041 (-1412229454636253184)]} 0 0
[junit4:junit4]   2> 4245 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4246 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10041 (-1412229454639398912)]} 0 4
[junit4:junit4]   2> 4247 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 4248 T962 C87 P58144 /update {wt=javabin&version=2} {add=[47 (1412229454633107456)]} 0 11
[junit4:junit4]   2> 4251 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10051 (1412229454646738944)]} 0 2
[junit4:junit4]   2> 4251 T948 C86 P34041 /update {wt=javabin&version=2} {add=[48 (1412229454646738945)]} 0 1
[junit4:junit4]   2> 4260 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4261 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4261 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10051 (1412229454650933248)]} 0 8
[junit4:junit4]   2> 4262 T963 C87 P58144 /update {wt=javabin&version=2} {add=[48 (1412229454650933249)]} 0 8
[junit4:junit4]   2> 4265 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10052 (1412229454661419008)]} 0 1
[junit4:junit4]   2> 4268 T947 C86 P34041 /update {wt=javabin&version=2} {add=[49 (1412229454664564736)]} 0 2
[junit4:junit4]   2> 4277 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4277 T962 C87 P58144 /update {wt=javabin&version=2} {add=[49 (1412229454668759040)]} 0 7
[junit4:junit4]   2> 4279 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4281 T947 C86 P34041 /update {wt=javabin&version=2} {add=[50 (1412229454678196224)]} 0 1
[junit4:junit4]   2> 4283 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10052 (1412229454666661888)]} 0 15
[junit4:junit4]   2> 4287 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10043 (-1412229454685536256)]} 0 0
[junit4:junit4]   2> 4290 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4291 T962 C87 P58144 /update {wt=javabin&version=2} {add=[50 (1412229454683439104)]} 0 7
[junit4:junit4]   2> 4293 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4294 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10043 (-1412229454688681984)]} 0 5
[junit4:junit4]   2> 4294 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[41 (-1412229454692876288)]} 0 0
[junit4:junit4]   2> 4298 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10053 (1412229454694973440)]} 0 2
[junit4:junit4]   2> 4303 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4304 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[41 (-1412229454694973440)]} 0 8
[junit4:junit4]   2> 4307 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4308 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10053 (1412229454700216320)]} 0 8
[junit4:junit4]   2> 4308 T948 C86 P34041 /update {wt=javabin&version=2} {add=[51 (1412229454706507776)]} 0 2
[junit4:junit4]   2> 4316 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4317 T962 C87 P58144 /update {wt=javabin&version=2} {add=[51 (1412229454710702080)]} 0 6
[junit4:junit4]   2> 4320 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10054 (1412229454719090688)]} 0 1
[junit4:junit4]   2> 4321 T948 C86 P34041 /update {wt=javabin&version=2} {add=[52 (1412229454720139264)]} 0 1
[junit4:junit4]   2> 4331 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4332 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 4333 T963 C87 P58144 /update {wt=javabin&version=2} {add=[52 (1412229454724333568)]} 0 9
[junit4:junit4]   2> 4333 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10054 (1412229454724333569)]} 0 9
[junit4:junit4]   2> 4336 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[43 (-1412229454736916480)]} 0 0
[junit4:junit4]   2> 4337 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10055 (1412229454736916481)]} 0 1
[junit4:junit4]   2> 4341 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4342 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[43 (-1412229454740062208)]} 0 3
[junit4:junit4]   2> 4346 T947 C86 P34041 /update {wt=javabin&version=2} {add=[53 (1412229454746353664)]} 0 1
[junit4:junit4]   2> 4349 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4350 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10055 (1412229454741110784)]} 0 10
[junit4:junit4]   2> 4353 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10045 (-1412229454754742272)]} 0 0
[junit4:junit4]   2> 4355 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4356 T962 C87 P58144 /update {wt=javabin&version=2} {add=[53 (1412229454750547968)]} 0 7
[junit4:junit4]   2> 4358 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4359 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10045 (-1412229454757888000)]} 0 3
[junit4:junit4]   2> 4364 T947 C86 P34041 /update {wt=javabin&version=2} {add=[54 (1412229454759985152)]} 0 6
[junit4:junit4]   2> 4368 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10056 (1412229454764179456)]} 0 6
[junit4:junit4]   2> 4374 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4378 T963 C87 P58144 /update {wt=javabin&version=2} {add=[54 (1412229454772568064)]} 0 9
[junit4:junit4]   2> 4382 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4382 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10056 (1412229454774665216)]} 0 10
[junit4:junit4]   2> 4387 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10057 (1412229454788296704)]} 0 2
[junit4:junit4]   2> 4390 T948 C86 P34041 /update {wt=javabin&version=2} {add=[55 (1412229454784102400)]} 0 9
[junit4:junit4]   2> 4395 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4396 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10057 (1412229454793539584)]} 0 6
[junit4:junit4]   2> 4397 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4398 T963 C87 P58144 /update {wt=javabin&version=2} {add=[55 (1412229454796685312)]} 0 6
[junit4:junit4]   2> 4398 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10051 (-1412229454801928192)]} 0 0
[junit4:junit4]   2> 4401 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[45 (-1412229454805073920)]} 0 0
[junit4:junit4]   2> 4403 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4404 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10051 (-1412229454805073920)]} 0 3
[junit4:junit4]   2> 4407 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4407 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[45 (-1412229454808219648)]} 0 3
[junit4:junit4]   2> 4408 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10058 (1412229454811365376)]} 0 1
[junit4:junit4]   2> 4413 T948 C86 P34041 /update {wt=javabin&version=2} {add=[56 (1412229454815559680)]} 0 3
[junit4:junit4]   2> 4416 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4416 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10058 (1412229454815559680)]} 0 5
[junit4:junit4]   2> 4420 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4420 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10059 (1412229454823948288)]} 0 1
[junit4:junit4]   2> 4421 T963 C87 P58144 /update {wt=javabin&version=2} {add=[56 (1412229454819753984)]} 0 6
[junit4:junit4]   2> 4426 T947 C86 P34041 /update {wt=javabin&version=2} {add=[57 (1412229454830239744)]} 0 2
[junit4:junit4]   2> 4433 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4434 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4435 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10059 (1412229454833385472)]} 0 7
[junit4:junit4]   2> 4435 T962 C87 P58144 /update {wt=javabin&version=2} {add=[57 (1412229454834434048)]} 0 6
[junit4:junit4]   2> 4438 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[51 (-1412229454843871232)]} 0 0
[junit4:junit4]   2> 4439 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10060 (1412229454843871233)]} 0 1
[junit4:junit4]   2> 4446 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4447 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[51 (-1412229454850162688)]} 0 4
[junit4:junit4]   2> 4451 T948 C86 P34041 /update {wt=javabin&version=2} {add=[58 (1412229454856454144)]} 0 2
[junit4:junit4]   2> 4453 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 4461 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10060 (1412229454848065536)]} 0 19
[junit4:junit4]   2> 4465 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10054 (-1412229454871134208)]} 0 1
[junit4:junit4]   2> 4467 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4468 T963 C87 P58144 /update {wt=javabin&version=2} {add=[58 (1412229454867988480)]} 0 15
[junit4:junit4]   2> 4470 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4470 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10054 (-1412229454874279936)]} 0 3
[junit4:junit4]   2> 4475 T947 C86 P34041 /update {wt=javabin&version=2} {add=[59 (1412229454881619968)]} 0 2
[junit4:junit4]   2> 4475 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10061 (1412229454881619969)]} 0 2
[junit4:junit4]   2> 4483 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4484 T962 C87 P58144 /update {wt=javabin&version=2} {add=[59 (1412229454885814272)]} 0 6
[junit4:junit4]   2> 4490 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4491 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10061 (1412229454885814273)]} 0 13
[junit4:junit4]   2> 4491 T948 C86 P34041 /update {wt=javabin&version=2} {add=[60 (1412229454895251456)]} 0 5
[junit4:junit4]   2> 4495 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10062 (1412229454902591488)]} 0 2
[junit4:junit4]   2> 4499 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4500 T963 C87 P58144 /update {wt=javabin&version=2} {add=[60 (1412229454902591488)]} 0 6
[junit4:junit4]   2> 4502 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4503 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[54 (-1412229454912028672)]} 0 1
[junit4:junit4]   2> 4503 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10062 (1412229454906785792)]} 0 5
[junit4:junit4]   2> 4507 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10063 (1412229454915174400)]} 0 1
[junit4:junit4]   2> 4510 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4513 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[54 (-1412229454915174400)]} 0 8
[junit4:junit4]   2> 4517 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4517 T947 C86 P34041 /update {wt=javabin&version=2} {add=[61 (1412229454925660160)]} 0 1
[junit4:junit4]   2> 4517 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10063 (1412229454921465856)]} 0 5
[junit4:junit4]   2> 4520 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10056 (-1412229454929854464)]} 0 0
[junit4:junit4]   2> 4524 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4525 T963 C87 P58144 /update {wt=javabin&version=2} {add=[61 (1412229454929854464)]} 0 5
[junit4:junit4]   2> 4526 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4526 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10056 (-1412229454933000192)]} 0 3
[junit4:junit4]   2> 4530 T947 C86 P34041 /update {wt=javabin&version=2} {add=[62 (1412229454939291648)]} 0 2
[junit4:junit4]   2> 4530 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10064 (1412229454939291649)]} 0 1
[junit4:junit4]   2> 4537 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4538 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4538 T962 C87 P58144 /update {wt=javabin&version=2} {add=[62 (1412229454943485952)]} 0 6
[junit4:junit4]   2> 4539 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10064 (1412229454943485953)]} 0 7
[junit4:junit4]   2> 4542 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10065 (1412229454951874561)]} 0 1
[junit4:junit4]   2> 4542 T948 C86 P34041 /update {wt=javabin&version=2} {add=[63 (1412229454951874560)]} 0 1
[junit4:junit4]   2> 4552 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4553 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10065 (1412229454956068864)]} 0 8
[junit4:junit4]   2> 4555 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4556 T962 C87 P58144 /update {wt=javabin&version=2} {add=[63 (1412229454957117440)]} 0 11
[junit4:junit4]   2> 4558 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[56 (-1412229454969700352)]} 0 0
[junit4:junit4]   2> 4564 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4565 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[56 (-1412229454972846080)]} 0 4
[junit4:junit4]   2> 4568 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10066 (1412229454967603200)]} 0 13
[junit4:junit4]   2> 4576 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4577 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10066 (1412229454983331840)]} 0 7
[junit4:junit4]   2> 4578 T947 C86 P34041 /update {wt=javabin&version=2} {add=[64 (1412229454980186112)]} 0 10
[junit4:junit4]   2> 4580 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10057 (-1412229454992769024)]} 0 0
[junit4:junit4]   2> 4586 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4587 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10057 (-1412229454995914752)]} 0 4
[junit4:junit4]   2> 4590 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4591 T962 C87 P58144 /update {wt=javabin&version=2} {add=[64 (1412229454993817600)]} 0 10
[junit4:junit4]   2> 4593 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10067 (1412229455004303360)]} 0 2
[junit4:junit4]   2> 4596 T947 C86 P34041 /update {wt=javabin&version=2} {add=[65 (1412229455007449088)]} 0 2
[junit4:junit4]   2> 4602 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4604 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10067 (1412229455009546240)]} 0 8
[junit4:junit4]   2> 4604 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4686 T963 C87 P58144 /update {wt=javabin&version=2} {add=[65 (1412229455012691968)]} 0 88
[junit4:junit4]   2> 4690 T948 C86 P34041 /update {wt=javabin&version=2} {add=[66 (1412229455107063808)]} 0 2
[junit4:junit4]   2> 4690 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10068 (1412229455106015232)]} 0 2
[junit4:junit4]   2> 4698 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4698 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4699 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10068 (1412229455111258112)]} 0 6
[junit4:junit4]   2> 4699 T962 C87 P58144 /update {wt=javabin&version=2} {add=[66 (1412229455111258113)]} 0 6
[junit4:junit4]   2> 4702 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[57 (-1412229455120695297)]} 0 0
[junit4:junit4]   2> 4703 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10069 (1412229455120695296)]} 0 1
[junit4:junit4]   2> 4708 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4709 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[57 (-1412229455124889600)]} 0 4
[junit4:junit4]   2> 4710 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4710 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10069 (1412229455124889601)]} 0 5
[junit4:junit4]   2> 4712 T948 C86 P34041 /update {wt=javabin&version=2} {add=[67 (1412229455130132480)]} 0 1
[junit4:junit4]   2> 4713 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10058 (-1412229455132229632)]} 0 0
[junit4:junit4]   2> 4718 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4719 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10058 (-1412229455134326785)]} 0 4
[junit4:junit4]   2> 4719 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4720 T962 C87 P58144 /update {wt=javabin&version=2} {add=[67 (1412229455134326784)]} 0 5
[junit4:junit4]   2> 4722 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10070 (1412229455140618240)]} 0 1
[junit4:junit4]   2> 4723 T948 C86 P34041 /update {wt=javabin&version=2} {add=[68 (1412229455141666816)]} 0 1
[junit4:junit4]   2> 4731 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4732 T963 C87 P58144 /update {wt=javabin&version=2} {add=[68 (1412229455145861120)]} 0 7
[junit4:junit4]   2> 4734 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4734 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10070 (1412229455144812544)]} 0 9
[junit4:junit4]   2> 4736 T948 C86 P34041 /update {wt=javabin&version=2} {add=[69 (1412229455156346880)]} 0 1
[junit4:junit4]   2> 4737 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10059 (-1412229455157395456)]} 0 0
[junit4:junit4]   2> 4743 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4744 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4744 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10059 (-1412229455160541185)]} 0 4
[junit4:junit4]   2> 4745 T962 C87 P58144 /update {wt=javabin&version=2} {add=[69 (1412229455160541184)]} 0 6
[junit4:junit4]   2> 4747 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[58 (-1412229455167881217)]} 0 0
[junit4:junit4]   2> 4748 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10071 (1412229455167881216)]} 0 1
[junit4:junit4]   2> 4754 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4755 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[58 (-1412229455171026944)]} 0 5
[junit4:junit4]   2> 4758 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4759 T947 C86 P34041 /update {wt=javabin&version=2} {add=[70 (1412229455179415552)]} 0 2
[junit4:junit4]   2> 4759 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10071 (1412229455172075520)]} 0 9
[junit4:junit4]   2> 4762 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10060 (-1412229455183609856)]} 0 1
[junit4:junit4]   2> 4766 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4767 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4767 T963 C87 P58144 /update {wt=javabin&version=2} {add=[70 (1412229455183609856)]} 0 6
[junit4:junit4]   2> 4768 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10060 (-1412229455185707008)]} 0 4
[junit4:junit4]   2> 4770 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[59 (-1412229455191998464)]} 0 1
[junit4:junit4]   2> 4771 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10072 (1412229455191998465)]} 0 1
[junit4:junit4]   2> 4775 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4775 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[59 (-1412229455194095616)]} 0 3
[junit4:junit4]   2> 4777 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4778 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10072 (1412229455195144192)]} 0 5
[junit4:junit4]   2> 4779 T948 C86 P34041 /update {wt=javabin&version=2} {add=[71 (1412229455200387072)]} 0 1
[junit4:junit4]   2> 4781 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10061 (-1412229455203532800)]} 0 1
[junit4:junit4]   2> 4785 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4787 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4788 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10061 (-1412229455205629952)]} 0 5
[junit4:junit4]   2> 4788 T963 C87 P58144 /update {wt=javabin&version=2} {add=[71 (1412229455206678528)]} 0 5
[junit4:junit4]   2> 4790 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[60 (-1412229455212969984)]} 0 0
[junit4:junit4]   2> 4792 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10073 (1412229455212969985)]} 0 2
[junit4:junit4]   2> 4795 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4796 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[60 (-1412229455216115712)]} 0 3
[junit4:junit4]   2> 4798 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4798 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10073 (1412229455217164288)]} 0 4
[junit4:junit4]   2> 4800 T947 C86 P34041 /update {wt=javabin&version=2} {add=[72 (1412229455222407168)]} 0 1
[junit4:junit4]   2> 4801 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10074 (1412229455224504320)]} 0 0
[junit4:junit4]   2> 4806 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4806 T962 C87 P58144 /update {wt=javabin&version=2} {add=[72 (1412229455225552896)]} 0 4
[junit4:junit4]   2> 4808 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4809 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10074 (1412229455227650048)]} 0 5
[junit4:junit4]   2> 4809 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[61 (-1412229455232892928)]} 0 0
[junit4:junit4]   2> 4811 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10062 (-1412229455234990080)]} 0 0
[junit4:junit4]   2> 4815 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4815 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[61 (-1412229455236038656)]} 0 3
[junit4:junit4]   2> 4816 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4817 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10062 (-1412229455238135808)]} 0 3
[junit4:junit4]   2> 4819 T947 C86 P34041 /update {wt=javabin&version=2} {add=[73 (1412229455242330112)]} 0 1
[junit4:junit4]   2> 4820 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10075 (1412229455243378688)]} 0 1
[junit4:junit4]   2> 4828 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4832 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10075 (1412229455247572992)]} 0 9
[junit4:junit4]   2> 4835 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10064 (-1412229455260155904)]} 0 0
[junit4:junit4]   2> 4848 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=23 
[junit4:junit4]   2> 4849 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4849 T962 C87 P58144 /update {wt=javabin&version=2} {add=[73 (1412229455246524416)]} 0 27
[junit4:junit4]   2> 4850 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10064 (-1412229455263301632)]} 0 12
[junit4:junit4]   2> 4856 T948 C86 P34041 /update {wt=javabin&version=2} {add=[74 (1412229455277981696)]} 0 4
[junit4:junit4]   2> 4865 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4866 T963 C87 P58144 /update {wt=javabin&version=2} {add=[74 (1412229455286370304)]} 0 7
[junit4:junit4]   2> 4869 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[62 (-1412229455295807488)]} 0 1
[junit4:junit4]   2> 4875 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4875 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[62 (-1412229455298953216)]} 0 3
[junit4:junit4]   2> 4879 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10076 (1412229455277981697)]} 0 27
[junit4:junit4]   2> 4881 T948 C86 P34041 /update {wt=javabin&version=2} {add=[75 (1412229455305244672)]} 0 3
[junit4:junit4]   2> 4914 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4915 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10076 (1412229455337750528)]} 0 7
[junit4:junit4]   2> 4917 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10065 (-1412229455346139136)]} 0 0
[junit4:junit4]   2> 4921 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4922 T962 C87 P58144 /update {wt=javabin&version=2} {add=[75 (1412229455312584704)]} 0 38
[junit4:junit4]   2> 4922 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4923 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10065 (-1412229455349284864)]} 0 3
[junit4:junit4]   2> 4924 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[64 (-1412229455353479168)]} 0 0
[junit4:junit4]   2> 4927 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10077 (1412229455354527744)]} 0 2
[junit4:junit4]   2> 4929 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4930 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[64 (-1412229455356624896)]} 0 3
[junit4:junit4]   2> 4933 T947 C86 P34041 /update {wt=javabin&version=2} {add=[76 (1412229455361867776)]} 0 1
[junit4:junit4]   2> 4940 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4941 T963 C87 P58144 /update {wt=javabin&version=2} {add=[76 (1412229455366062080)]} 0 5
[junit4:junit4]   2> 4942 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4944 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[65 (-1412229455373402112)]} 0 1
[junit4:junit4]   2> 4943 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10077 (1412229455369207808)]} 0 5
[junit4:junit4]   2> 4948 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10078 (1412229455377596416)]} 0 1
[junit4:junit4]   2> 4949 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4950 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[65 (-1412229455377596416)]} 0 3
[junit4:junit4]   2> 4956 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4957 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10078 (1412229455381790720)]} 0 6
[junit4:junit4]   2> 4958 T947 C86 P34041 /update {wt=javabin&version=2} {add=[77 (1412229455389130752)]} 0 1
[junit4:junit4]   2> 4962 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10079 (1412229455391227904)]} 0 2
[junit4:junit4]   2> 4965 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4966 T963 C87 P58144 /update {wt=javabin&version=2} {add=[77 (1412229455392276480)]} 0 5
[junit4:junit4]   2> 4969 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4969 T948 C86 P34041 /update {wt=javabin&version=2} {add=[78 (1412229455399616512)]} 0 1
[junit4:junit4]   2> 4969 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10079 (1412229455396470784)]} 0 4
[junit4:junit4]   2> 4972 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10080 (1412229455403810816)]} 0 1
[junit4:junit4]   2> 4977 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4977 T963 C87 P58144 /update {wt=javabin&version=2} {add=[78 (1412229455402762240)]} 0 6
[junit4:junit4]   2> 4978 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4979 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10080 (1412229455405907968)]} 0 5
[junit4:junit4]   2> 4981 T948 C86 P34041 /update {wt=javabin&version=2} {add=[79 (1412229455412199424)]} 0 1
[junit4:junit4]   2> 4981 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10067 (-1412229455413248000)]} 0 1
[junit4:junit4]   2> 4985 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4986 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10067 (-1412229455415345152)]} 0 3
[junit4:junit4]   2> 4987 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4987 T962 C87 P58144 /update {wt=javabin&version=2} {add=[79 (1412229455415345153)]} 0 4
[junit4:junit4]   2> 4989 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10081 (1412229455420588032)]} 0 1
[junit4:junit4]   2> 4990 T948 C86 P34041 /update {wt=javabin&version=2} {add=[80 (1412229455422685184)]} 0 0
[junit4:junit4]   2> 4998 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4999 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10081 (1412229455423733760)]} 0 8
[junit4:junit4]   2> 5000 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5000 T963 C87 P58144 /update {wt=javabin&version=2} {add=[80 (1412229455425830912)]} 0 7
[junit4:junit4]   2> 5002 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10082 (1412229455434219520)]} 0 1
[junit4:junit4]   2> 5002 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[67 (-1412229455435268096)]} 0 0
[junit4:junit4]   2> 5007 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5008 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[67 (-1412229455438413824)]} 0 4
[junit4:junit4]   2> 5008 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5009 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10082 (1412229455437365248)]} 0 5
[junit4:junit4]   2> 5011 T947 C86 P34041 /update {wt=javabin&version=2} {add=[81 (1412229455444705280)]} 0 1
[junit4:junit4]   2> 5012 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10083 (1412229455444705281)]} 0 1
[junit4:junit4]   2> 5018 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5018 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5019 T963 C87 P58144 /update {wt=javabin&version=2} {add=[81 (1412229455447851008)]} 0 5
[junit4:junit4]   2> 5019 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10083 (1412229455447851009)]} 0 5
[junit4:junit4]   2> 5022 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10084 (1412229455456239617)]} 0 1
[junit4:junit4]   2> 5022 T948 C86 P34041 /update {wt=javabin&version=2} {add=[82 (1412229455456239616)]} 0 1
[junit4:junit4]   2> 5029 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5029 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10084 (1412229455459385345)]} 0 4
[junit4:junit4]   2> 5030 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5030 T963 C87 P58144 /update {wt=javabin&version=2} {add=[82 (1412229455459385344)]} 0 5
[junit4:junit4]   2> 5034 T947 C86 P34041 /update {wt=javabin&version=2} {add=[83 (1412229455467773952)]} 0 1
[junit4:junit4]   2> 5036 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10085 (1412229455466725376)]} 0 4
[junit4:junit4]   2> 5043 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5043 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 5043 T963 C87 P58144 /update {wt=javabin&version=2} {add=[83 (1412229455470919680)]} 0 7
[junit4:junit4]   2> 5044 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10085 (1412229455473016832)]} 0 6
[junit4:junit4]   2> 5047 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10086 (1412229455481405441)]} 0 1
[junit4:junit4]   2> 5047 T948 C86 P34041 /update {wt=javabin&version=2} {add=[84 (1412229455481405440)]} 0 1
[junit4:junit4]   2> 5054 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5055 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10086 (1412229455485599745)]} 0 5
[junit4:junit4]   2> 5056 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5057 T963 C87 P58144 /update {wt=javabin&version=2} {add=[84 (1412229455485599744)]} 0 7
[junit4:junit4]   2> 5058 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10087 (1412229455492939776)]} 0 1
[junit4:junit4]   2> 5063 T947 C86 P34041 /update {wt=javabin&version=2} {add=[85 (1412229455495036928)]} 0 4
[junit4:junit4]   2> 5064 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5065 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10087 (1412229455496085504)]} 0 5
[junit4:junit4]   2> 5068 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10088 (1412229455503425536)]} 0 1
[junit4:junit4]   2> 5069 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5069 T962 C87 P58144 /update {wt=javabin&version=2} {add=[85 (1412229455502376960)]} 0 4
[junit4:junit4]   2> 5072 T947 C86 P34041 /update {wt=javabin&version=2} {add=[86 (1412229455507619840)]} 0 1
[junit4:junit4]   2> 5074 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5074 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10088 (1412229455507619840)]} 0 4
[junit4:junit4]   2> 5077 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10068 (-1412229455513911296)]} 0 0
[junit4:junit4]   2> 5078 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5078 T962 C87 P58144 /update {wt=javabin&version=2} {add=[86 (1412229455510765568)]} 0 4
[junit4:junit4]   2> 5081 T947 C86 P34041 /update {wt=javabin&version=2} {add=[87 (1412229455518105600)]} 0 1
[junit4:junit4]   2> 5082 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5082 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10068 (-1412229455516008448)]} 0 3
[junit4:junit4]   2> 5088 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5089 T962 C87 P58144 /update {wt=javabin&version=2} {add=[87 (1412229455521251328)]} 0 6
[junit4:junit4]   2> 5090 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10089 (1412229455526494208)]} 0 1
[junit4:junit4]   2> 5093 T948 C86 P34041 /update {wt=javabin&version=2} {add=[88 (1412229455529639936)]} 0 1
[junit4:junit4]   2> 5099 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5100 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10089 (1412229455532785664)]} 0 5
[junit4:junit4]   2> 5103 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 5104 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10090 (1412229455541174272)]} 0 2
[junit4:junit4]   2> 5104 T963 C87 P58144 /update {wt=javabin&version=2} {add=[88 (1412229455533834240)]} 0 8
[junit4:junit4]   2> 5107 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[68 (-1412229455545368576)]} 0 0
[junit4:junit4]   2> 5111 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5112 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10090 (1412229455545368576)]} 0 5
[junit4:junit4]   2> 5112 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5112 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[68 (-1412229455547465728)]} 0 3
[junit4:junit4]   2> 5115 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10091 (1412229455552708608)]} 0 1
[junit4:junit4]   2> 5116 T947 C86 P34041 /update {wt=javabin&version=2} {add=[89 (1412229455553757184)]} 0 2
[junit4:junit4]   2> 5121 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5122 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10091 (1412229455555854336)]} 0 5
[junit4:junit4]   2> 5124 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5125 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10069 (-1412229455563194368)]} 0 0
[junit4:junit4]   2> 5125 T962 C87 P58144 /update {wt=javabin&version=2} {add=[89 (1412229455556902912)]} 0 7
[junit4:junit4]   2> 5128 T947 C86 P34041 /update {wt=javabin&version=2} {add=[90 (1412229455566340096)]} 0 1
[junit4:junit4]   2> 5130 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5130 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10069 (-1412229455566340096)]} 0 3
[junit4:junit4]   2> 5133 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10092 (1412229455571582976)]} 0 1
[junit4:junit4]   2> 5136 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5137 T962 C87 P58144 /update {wt=javabin&version=2} {add=[90 (1412229455570534400)]} 0 6
[junit4:junit4]   2> 5140 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5140 T947 C86 P34041 /update {wt=javabin&version=2} {add=[91 (1412229455578923008)]} 0 1
[junit4:junit4]   2> 5141 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10092 (1412229455575777280)]} 0 6
[junit4:junit4]   2> 5143 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10070 (-1412229455583117312)]} 0 0
[junit4:junit4]   2> 5147 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5147 T962 C87 P58144 /update {wt=javabin&version=2} {add=[91 (1412229455583117312)]} 0 5
[junit4:junit4]   2> 5148 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5148 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10070 (-1412229455586263040)]} 0 3
[junit4:junit4]   2> 5150 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[69 (-1412229455589408768)]} 0 1
[junit4:junit4]   2> 5151 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10093 (1412229455591505920)]} 0 1
[junit4:junit4]   2> 5154 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5155 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[69 (-1412229455592554496)]} 0 3
[junit4:junit4]   2> 5158 T948 C86 P34041 /update {wt=javabin&version=2} {add=[92 (1412229455597797376)]} 0 1
[junit4:junit4]   2> 5158 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5158 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10093 (1412229455594651648)]} 0 4
[junit4:junit4]   2> 5162 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10094 (1412229455601991680)]} 0 1
[junit4:junit4]   2> 5164 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5165 T963 C87 P58144 /update {wt=javabin&version=2} {add=[92 (1412229455600943104)]} 0 5
[junit4:junit4]   2> 5167 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[70 (-1412229455608283136)]} 0 0
[junit4:junit4]   2> 5168 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5169 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10094 (1412229455605137408)]} 0 5
[junit4:junit4]   2> 5171 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10095 (1412229455612477440)]} 0 0
[junit4:junit4]   2> 5172 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5173 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[70 (-1412229455611428864)]} 0 3
[junit4:junit4]   2> 5179 T948 C86 P34041 /update {wt=javabin&version=2} {add=[93 (1412229455616671744)]} 0 4
[junit4:junit4]   2> 5181 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 5182 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10095 (1412229455615623168)]} 0 8
[junit4:junit4]   2> 5186 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10096 (1412229455626108928)]} 0 2
[junit4:junit4]   2> 5186 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5194 T963 C87 P58144 /update {wt=javabin&version=2} {add=[93 (1412229455624011776)]} 0 12
[junit4:junit4]   2> 5198 T948 C86 P34041 /update {wt=javabin&version=2} {add=[94 (1412229455639740416)]} 0 1
[junit4:junit4]   2> 5199 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5199 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10096 (1412229455637643264)]} 0 4
[junit4:junit4]   2> 5202 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10072 (-1412229455644983296)]} 0 0
[junit4:junit4]   2> 5206 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5207 T963 C87 P58144 /update {wt=javabin&version=2} {add=[94 (1412229455642886144)]} 0 7
[junit4:junit4]   2> 5208 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5210 T948 C86 P34041 /update {wt=javabin&version=2} {add=[95 (1412229455653371904)]} 0 0
[junit4:junit4]   2> 5210 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10072 (-1412229455648129024)]} 0 6
[junit4:junit4]   2> 5215 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10097 (1412229455658614784)]} 0 1
[junit4:junit4]   2> 5217 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5217 T962 C87 P58144 /update {wt=javabin&version=2} {add=[95 (1412229455656517632)]} 0 4
[junit4:junit4]   2> 5220 T948 C86 P34041 /update {wt=javabin&version=2} {add=[96 (1412229455663857664)]} 0 0
[junit4:junit4]   2> 5221 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5222 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10097 (1412229455661760512)]} 0 4
[junit4:junit4]   2> 5226 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10098 (1412229455669100544)]} 0 2
[junit4:junit4]   2> 5226 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5227 T962 C87 P58144 /update {wt=javabin&version=2} {add=[96 (1412229455667003392)]} 0 4
[junit4:junit4]   2> 5229 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[72 (-1412229455673294848)]} 0 0
[junit4:junit4]   2> 5232 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5232 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10098 (1412229455672246272)]} 0 4
[junit4:junit4]   2> 5235 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10074 (-1412229455678537728)]} 0 1
[junit4:junit4]   2> 5236 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5236 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[72 (-1412229455675392000)]} 0 5
[junit4:junit4]   2> 5239 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5240 T948 C86 P34041 /update {wt=javabin&version=2} {add=[97 (1412229455683780608)]} 0 2
[junit4:junit4]   2> 5240 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10074 (-1412229455681683456)]} 0 3
[junit4:junit4]   2> 5243 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10099 (1412229455686926336)]} 0 1
[junit4:junit4]   2> 5246 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5246 T963 C87 P58144 /update {wt=javabin&version=2} {add=[97 (1412229455687974912)]} 0 4
[junit4:junit4]   2> 5249 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5249 T948 C86 P34041 /update {wt=javabin&version=2} {add=[98 (1412229455693217792)]} 0 1
[junit4:junit4]   2> 5250 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10099 (1412229455691120640)]} 0 4
[junit4:junit4]   2> 5252 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10077 (-1412229455697412096)]} 0 0
[junit4:junit4]   2> 5256 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5257 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 5257 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10077 (-1412229455699509248)]} 0 3
[junit4:junit4]   2> 5257 T963 C87 P58144 /update {wt=javabin&version=2} {add=[98 (1412229455696363520)]} 0 6
[junit4:junit4]   2> 5260 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[74 (-1412229455705800704)]} 0 1
[junit4:junit4]   2> 5263 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10100 (1412229455704752128)]} 0 4
[junit4:junit4]   2> 5264 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5265 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[74 (-1412229455707897856)]} 0 3
[junit4:junit4]   2> 5269 T948 C86 P34041 /update {wt=javabin&version=2} {add=[99 (1412229455713140736)]} 0 2
[junit4:junit4]   2> 5270 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5270 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10100 (1412229455712092160)]} 0 5
[junit4:junit4]   2> 5273 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10080 (-1412229455719432192)]} 0 0
[junit4:junit4]   2> 5276 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5276 T963 C87 P58144 /update {wt=javabin&version=2} {add=[99 (1412229455717335040)]} 0 5
[junit4:junit4]   2> 5278 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5279 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10080 (-1412229455722577920)]} 0 3
[junit4:junit4]   2> 5279 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[77 (-1412229455725723648)]} 0 1
[junit4:junit4]   2> 5283 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5283 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10101 (1412229455727820800)]} 0 2
[junit4:junit4]   2> 5283 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[77 (-1412229455727820800)]} 0 2
[junit4:junit4]   2> 5286 T948 C86 P34041 /update {wt=javabin&version=2} {add=[100 (1412229455733063680)]} 0 1
[junit4:junit4]   2> 5293 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5293 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5293 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10101 (1412229455733063680)]} 0 8
[junit4:junit4]   2> 5294 T962 C87 P58144 /update {wt=javabin&version=2} {add=[100 (1412229455736209408)]} 0 6
[junit4:junit4]   2> 5296 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[80 (-1412229455743549440)]} 0 0
[junit4:junit4]   2> 5297 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10102 (1412229455743549441)]} 0 1
[junit4:junit4]   2> 5300 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5301 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[80 (-1412229455745646592)]} 0 3
[junit4:junit4]   2> 5302 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5303 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10102 (1412229455746695168)]} 0 4
[junit4:junit4]   2> 5305 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10103 (1412229455752986624)]} 0 0
[junit4:junit4]   2> 5307 T947 C86 P34041 /update {wt=javabin&version=2} {add=[101 (1412229455750889472)]} 0 4
[junit4:junit4]   2> 5310 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5311 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10103 (1412229455756132352)]} 0 4
[junit4:junit4]   2> 5312 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5313 T962 C87 P58144 /update {wt=javabin&version=2} {add=[101 (1412229455757180928)]} 0 4
[junit4:junit4]   2> 5315 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10104 (1412229455761375232)]} 0 2
[junit4:junit4]   2> 5316 T948 C86 P34041 /update {wt=javabin&version=2} {add=[102 (1412229455763472384)]} 0 1
[junit4:junit4]   2> 5321 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5322 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5322 T963 C87 P58144 /update {wt=javabin&version=2} {add=[102 (1412229455766618112)]} 0 4
[junit4:junit4]   2> 5322 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10104 (1412229455765569536)]} 0 5
[junit4:junit4]   2> 5325 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10083 (-1412229455773958144)]} 0 1
[junit4:junit4]   2> 5325 T948 C86 P34041 /update {wt=javabin&version=2} {add=[103 (1412229455772909568)]} 0 1
[junit4:junit4]   2> 5329 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5329 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10083 (-1412229455776055296)]} 0 2
[junit4:junit4]   2> 5332 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10105 (1412229455781298176)]} 0 1
[junit4:junit4]   2> 5333 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 5334 T963 C87 P58144 /update {wt=javabin&version=2} {add=[103 (1412229455776055297)]} 0 7
[junit4:junit4]   2> 5337 T948 C86 P34041 /update {wt=javabin&version=2} {add=[104 (1412229455785492480)]} 0 1
[junit4:junit4]   2> 5338 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5339 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10105 (1412229455784443904)]} 0 5
[junit4:junit4]   2> 5341 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10085 (-1412229455790735360)]} 0 0
[junit4:junit4]   2> 5342 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5342 T963 C87 P58144 /update {wt=javabin&version=2} {add=[104 (1412229455788638208)]} 0 3
[junit4:junit4]   2> 5344 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[83 (-1412229455793881088)]} 0 0
[junit4:junit4]   2> 5345 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5346 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10085 (-1412229455792832512)]} 0 3
[junit4:junit4]   2> 5348 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5349 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10106 (1412229455798075392)]} 0 1
[junit4:junit4]   2> 5349 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[83 (-1412229455795978240)]} 0 3
[junit4:junit4]   2> 5354 T948 C86 P34041 /update {wt=javabin&version=2} {add=[105 (1412229455801221120)]} 0 3
[junit4:junit4]   2> 5357 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5357 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10106 (1412229455801221120)]} 0 6
[junit4:junit4]   2> 5359 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10087 (-1412229455809609728)]} 0 0
[junit4:junit4]   2> 5360 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5361 T962 C87 P58144 /update {wt=javabin&version=2} {add=[105 (1412229455806464000)]} 0 5
[junit4:junit4]   2> 5363 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[85 (-1412229455813804032)]} 0 0
[junit4:junit4]   2> 5364 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5364 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10087 (-1412229455812755456)]} 0 2
[junit4:junit4]   2> 5367 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10107 (1412229455816949760)]} 0 1
[junit4:junit4]   2> 5367 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5368 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[85 (-1412229455815901184)]} 0 3
[junit4:junit4]   2> 5371 T948 C86 P34041 /update {wt=javabin&version=2} {add=[106 (1412229455821144064)]} 0 1
[junit4:junit4]   2> 5373 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5373 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10107 (1412229455821144064)]} 0 3
[junit4:junit4]   2> 5376 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10108 (1412229455826386944)]} 0 1
[junit4:junit4]   2> 5376 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5377 T962 C87 P58144 /update {wt=javabin&version=2} {add=[106 (1412229455824289792)]} 0 4
[junit4:junit4]   2> 5379 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[87 (-1412229455830581248)]} 0 0
[junit4:junit4]   2> 5381 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5382 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10108 (1412229455829532672)]} 0 4
[junit4:junit4]   2> 5383 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5383 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[87 (-1412229455832678400)]} 0 2
[junit4:junit4]   2> 5384 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10088 (-1412229455835824128)]} 0 0
[junit4:junit4]   2> 5386 T947 C86 P34041 /update {wt=javabin&version=2} {add=[107 (1412229455837921280)]} 0 1
[junit4:junit4]   2> 5388 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5389 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[10088 (-1412229455838969856)]} 0 3
[junit4:junit4]   2> 5392 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5392 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10109 (1412229455843164160)]} 0 1
[junit4:junit4]   2> 5393 T963 C87 P58144 /update {wt=javabin&version=2} {add=[107 (1412229455841067008)]} 0 5
[junit4:junit4]   2> 5396 T947 C86 P34041 /update {wt=javabin&version=2} {add=[108 (1412229455847358464)]} 0 1
[junit4:junit4]   2> 5398 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5398 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10109 (1412229455847358464)]} 0 4
[junit4:junit4]   2> 5402 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10110 (1412229455853649920)]} 0 1
[junit4:junit4]   2> 5409 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5410 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10110 (1412229455857844224)]} 0 6
[junit4:junit4]   2> 5414 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10111 (1412229455866232832)]} 0 1
[junit4:junit4]   2> 5417 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=15 
[junit4:junit4]   2> 5418 T963 C87 P58144 /update {wt=javabin&version=2} {add=[108 (1412229455850504192)]} 0 20
[junit4:junit4]   2> 5420 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5421 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10111 (1412229455869378560)]} 0 5
[junit4:junit4]   2> 5422 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[88 (-1412229455875670016)]} 0 0
[junit4:junit4]   2> 5423 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10089 (-1412229455876718592)]} 0 0
[junit4:junit4]   2> 5427 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5428 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5428 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[88 (-1412229455878815744)]} 0 3
[junit4:junit4]   2> 5428 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10089 (-1412229455879864320)]} 0 2
[junit4:junit4]   2> 5431 T948 C86 P34041 /update {wt=javabin&version=2} {add=[109 (1412229455885107200)]} 0 1
[junit4:junit4]   2> 5432 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10112 (1412229455885107201)]} 0 1
[junit4:junit4]   2> 5440 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5441 T963 C87 P58144 /update {wt=javabin&version=2} {add=[109 (1412229455888252929)]} 0 7
[junit4:junit4]   2> 5444 T947 C86 P34041 /update {wt=javabin&version=2} {add=[110 (1412229455898738688)]} 0 1
[junit4:junit4]   2> 5452 T974 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 5453 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 5453 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10112 (1412229455888252928)]} 0 19
[junit4:junit4]   2> 5453 T963 C87 P58144 /update {wt=javabin&version=2} {add=[110 (1412229455901884416)]} 0 6
[junit4:junit4]   2> 5456 T947 C86 P34041 /update {wt=javabin&version=2} {delete=[10090 (-1412229455911321600)]} 0 1
[junit4:junit4]   2> 5458 T948 C86 P34041 /update {wt=javabin&version=2} {add=[111 (1412229455912370176)]} 0 1
[junit4:junit4]   2> 5467 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5468 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10090 (-1412229455914467328)]} 0 10
[junit4:junit4]   2> 5472 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10113 (1412229455927050240)]} 0 1
[junit4:junit4]   2> 5475 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5475 T962 C87 P58144 /update {wt=javabin&version=2} {add=[111 (1412229455923904512)]} 0 7
[junit4:junit4]   2> 5478 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[89 (-1412229455934390272)]} 0 0
[junit4:junit4]   2> 5479 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5480 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10113 (1412229455931244544)]} 0 6
[junit4:junit4]   2> 5483 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5483 T948 C86 P34041 /update {wt=javabin&version=2} {add=[10114 (1412229455938584576)]} 0 1
[junit4:junit4]   2> 5484 T962 C87 P58144 /update {wt=javabin&version=2} {delete=[89 (-1412229455937536000)]} 0 3
[junit4:junit4]   2> 5487 T948 C86 P34041 /update {wt=javabin&version=2} {add=[112 (1412229455942778880)]} 0 1
[junit4:junit4]   2> 5489 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5489 T963 C87 P58144 /update {wt=javabin&version=2} {add=[10114 (1412229455942778880)]} 0 3
[junit4:junit4]   2> 5492 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[10093 (-1412229455949070336)]} 0 0
[junit4:junit4]   2> 5494 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5495 T962 C87 P58144 /update {wt=javabin&version=2} {add=[112 (1412229455946973184)]} 0 5
[junit4:junit4]   2> 5496 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5497 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[10093 (-1412229455951167488)]} 0 3
[junit4:junit4]   2> 5497 T948 C86 P34041 /update {wt=javabin&version=2} {delete=[90 (-1412229455954313216)]} 0 0
[junit4:junit4]   2> 5501 T947 C86 P34041 /update {wt=javabin&version=2} {add=[10115 (1412229455957458944)]} 0 2
[junit4:junit4]   2> 5502 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5503 T963 C87 P58144 /update {wt=javabin&version=2} {delete=[90 (-1412229455957458944)]} 0 3
[junit4:junit4]   2> 5511 T973 C85 P37287 REQ /update {distrib.from=http://127.0.0.1:58144/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 5511 T962 C87 P58144 /update {wt=javabin&version=2} {add=[10115 (1412229455960604672)]} 0 8


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

valuate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$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> 25749 T1092 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41963
[junit4:junit4]   2> 25849 T1093 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 25849 T935 oaz.ZooKeeper.close Session: 0x13993f33e050007 closed
[junit4:junit4]   2> 25851 T935 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 25933 T935 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=DA9F99713245DD6F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr -Dtests.timezone=Pacific/Pohnpei -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   26.0s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://localhost:37287/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([DA9F99713245DD6F:5B791769451ABD53]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
[junit4:junit4]    > 	at org.apache.solr.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:601)
[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> 25971 T935 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Appending, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=sr, timezone=Pacific/Pohnpei
[junit4:junit4]   2> NOTE: Linux 3.2.0-29-generic i386/Oracle Corporation 1.7.0_07 (32-bit)/cpus=8,threads=1,free=13113480,total=80617472
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestReload, TestStressLucene, UUIDFieldTest, ShowFileRequestHandlerTest, TestCSVResponseWriter, TermVectorComponentTest, TimeZoneUtilsTest, LukeRequestHandlerTest, TestRecovery, TestReversedWildcardFilterFactory, TestSolrCoreProperties, ClusterStateUpdateTest, SolrInfoMBeanTest, ZkSolrClientTest, IndexSchemaRuntimeFieldTest, DistributedQueryElevationComponentTest, PolyFieldTest, SuggesterTest, TestMultiCoreConfBootstrap, TestFoldingMultitermQuery, UpdateParamsTest, TestRealTimeGet, TestSolrDeletionPolicy2, StatelessScriptUpdateProcessorFactoryTest, TestSolrDeletionPolicy1, OverseerTest, QueryParsingTest, TestArbitraryIndexDir, TestPhraseSuggestions, FieldMutatingUpdateProcessorTest, DisMaxRequestHandlerTest, TestOmitPositions, DocumentBuilderTest, XmlUpdateRequestHandlerTest, TestBM25SimilarityFactory, FileUtilsTest, StatsComponentTest, DebugComponentTest, TestPluginEnable, TestJmxIntegration, TestCSVLoader, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 26.00s, 1 test, 1 error <<< FAILURES!

[...truncated 482 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:146: 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:1065: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:750: There were test failures: 228 suites, 917 tests, 1 error, 6 ignored (1 assumption)

Total time: 20 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.7.0_07 -server -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message