lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (32bit/ibm-j9-jdk6) - Build # 2055 - Failure!
Date Mon, 29 Oct 2012 03:49:05 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/2055/
Java: 32bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

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

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:40940/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([79897B6D3F80E80A:F86FF57548DF8836]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
	at java.lang.reflect.Method.invoke(Method.java:611)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:738)




Build Log:
[...truncated 8008 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 3 T1672 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-RecoveryZkTest-1351482087656
[junit4:junit4]   2> 4 T1672 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 6 T1673 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 6 T1673 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 7 T1673 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 105 T1672 oasc.ZkTestServer.run start zk server on port:34231
[junit4:junit4]   2> 105 T1672 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34231 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@63a563a5
[junit4:junit4]   2> 106 T1678 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:34231
[junit4:junit4]   2> 106 T1672 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 107 T1678 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34231, initiating session
[junit4:junit4]   2> 107 T1674 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52032
[junit4:junit4]   2> 108 T1674 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52032
[junit4:junit4]   2> 109 T1676 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 316 T1676 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aaa9c18ed0000 with negotiated timeout 10000 for client /127.0.0.1:52032
[junit4:junit4]   2> 335 T1678 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:34231, sessionid = 0x13aaa9c18ed0000, negotiated timeout = 10000
[junit4:junit4]   2> 336 T1679 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63a563a5 name:ZooKeeperConnection Watcher:127.0.0.1:34231 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 336 T1672 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 337 T1672 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 339 T1677 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aaa9c18ed0000
[junit4:junit4]   2> 340 T1679 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 340 T1672 oaz.ZooKeeper.close Session: 0x13aaa9c18ed0000 closed
[junit4:junit4]   2> 340 T1674 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52032 which had sessionid 0x13aaa9c18ed0000
[junit4:junit4]   2> 341 T1672 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34231/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2c7f2c7f
[junit4:junit4]   2> 343 T1680 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:34231
[junit4:junit4]   2> 343 T1672 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 344 T1680 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34231, initiating session
[junit4:junit4]   2> 344 T1674 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52033
[junit4:junit4]   2> 345 T1674 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52033
[junit4:junit4]   2> 346 T1676 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aaa9c18ed0001 with negotiated timeout 10000 for client /127.0.0.1:52033
[junit4:junit4]   2> 346 T1680 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:34231, sessionid = 0x13aaa9c18ed0001, negotiated timeout = 10000
[junit4:junit4]   2> 347 T1681 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2c7f2c7f name:ZooKeeperConnection Watcher:127.0.0.1:34231/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 347 T1672 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 348 T1672 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 350 T1672 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 352 T1672 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 353 T1672 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 355 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 356 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 359 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 361 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 363 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 364 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 366 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 367 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 368 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 369 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 371 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 371 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 373 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 374 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 375 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 376 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 378 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 378 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 380 T1672 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 381 T1672 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 382 T1677 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aaa9c18ed0001
[junit4:junit4]   2> 383 T1681 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 383 T1672 oaz.ZooKeeper.close Session: 0x13aaa9c18ed0001 closed
[junit4:junit4]   2> 383 T1674 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52033 which had sessionid 0x13aaa9c18ed0001
[junit4:junit4]   2> 385 T1672 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 388 T1672 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:54839
[junit4:junit4]   2> 390 T1672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 390 T1672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 391 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 403 T1672 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 404 T1672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 405 T1672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 406 T1672 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 406 T1672 oasc.CoreContainer.<init> New CoreContainer 251399932
[junit4:junit4]   2> 407 T1672 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 408 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 428 T1672 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 439 T1672 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:34231/solr
[junit4:junit4]   2> 440 T1672 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34231/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@43a043a0
[junit4:junit4]   2> 441 T1691 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:34231
[junit4:junit4]   2> 441 T1672 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 442 T1691 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34231, initiating session
[junit4:junit4]   2> 442 T1674 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52034
[junit4:junit4]   2> 443 T1674 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52034
[junit4:junit4]   2> 444 T1676 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aaa9c18ed0002 with negotiated timeout 5000 for client /127.0.0.1:52034
[junit4:junit4]   2> 444 T1691 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:34231, sessionid = 0x13aaa9c18ed0002, negotiated timeout = 5000
[junit4:junit4]   2> 445 T1692 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@43a043a0 name:ZooKeeperConnection Watcher:127.0.0.1:34231/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 445 T1672 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 447 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 448 T1672 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 449 T1672 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54839_solr
[junit4:junit4]   2> 450 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:54839_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:54839_solr
[junit4:junit4]   2> 451 T1672 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54839_solr
[junit4:junit4]   2> 452 T1672 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 453 T1672 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 453 T1672 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 454 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 455 T1672 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 455 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 456 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 457 T1672 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 457 T1672 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 458 T1672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 463 T1672 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 465 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 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> 466 T1672 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 468 T1672 oasc.Overseer.start Overseer (id=88570730096951298-127.0.0.1:54839_solr-n_0000000000) starting
[junit4:junit4]   2> 468 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 469 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 470 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 471 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 473 T1694 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 473 T1672 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 474 T1672 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 475 T1693 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 478 T1672 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 479 T1672 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 480 T1672 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 480 T1672 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 482 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 483 T1672 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 484 T1672 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 522 T1672 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 571 T1672 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 572 T1672 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 577 T1672 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1004 T1672 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1010 T1672 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1013 T1672 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1021 T1672 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1025 T1672 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1029 T1672 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1030 T1672 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1032 T1672 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351482087656/control/data/
[junit4:junit4]   2> 1032 T1672 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7550755
[junit4:junit4]   2> 1034 T1672 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351482087656/control/data/index/
[junit4:junit4]   2> 1035 T1672 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351482087656/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1036 T1672 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1351482087656/control/data/index forceNew:false
[junit4:junit4]   2> 1041 T1672 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1351482087656/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1042 T1672 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1043 T1672 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1045 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1046 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1047 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1048 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1048 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1049 T1672 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1050 T1672 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1051 T1672 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1052 T1672 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1053 T1672 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1053 T1672 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1054 T1672 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1055 T1672 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1056 T1672 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1057 T1672 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1058 T1672 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1058 T1672 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1059 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1060 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1061 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1062 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1063 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1063 T1672 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1064 T1672 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1065 T1672 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1067 T1672 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1068 T1672 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1070 T1672 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1073 T1672 oass.SolrIndexSearcher.<init> Opening Searcher@8340834 main
[junit4:junit4]   2> 1074 T1672 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1075 T1672 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1076 T1672 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1083 T1672 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1130 T1695 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8340834 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1141 T1672 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1143 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1144 T1672 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1145 T1672 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:54839/solr shardId:control_shard
[junit4:junit4]   2> 1152 T1672 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1155 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:delete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1156 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1157 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1493 T1693 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1494 T1693 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:54839/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:54839_solr",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 1495 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1498 T1692 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1658 T1672 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1659 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1660 T1672 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54839/solr/collection1/
[junit4:junit4]   2> 1661 T1672 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1661 T1672 oasc.SyncStrategy.syncToMe http://127.0.0.1:54839/solr/collection1/ has no replicas
[junit4:junit4]   2> 1662 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54839/solr/collection1/
[junit4:junit4]   2> 1663 T1672 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1666 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2001 T1693 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2003 T1692 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2018 T1672 oasc.ZkController.register We are http://127.0.0.1:54839/solr/collection1/ and leader is http://127.0.0.1:54839/solr/collection1/
[junit4:junit4]   2> 2018 T1672 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54839/solr
[junit4:junit4]   2> 2019 T1672 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2020 T1672 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2022 T1672 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2023 T1672 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 2024 T1672 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2025 T1672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2266 T1672 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2268 T1672 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:50827
[junit4:junit4]   2> 2269 T1672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2270 T1672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682
[junit4:junit4]   2> 2270 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682/'
[junit4:junit4]   2> 2290 T1672 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2291 T1672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2292 T1672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682
[junit4:junit4]   2> 2292 T1672 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682/solr.xml
[junit4:junit4]   2> 2293 T1672 oasc.CoreContainer.<init> New CoreContainer 1655268009
[junit4:junit4]   2> 2294 T1672 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682/'
[junit4:junit4]   2> 2294 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682/'
[junit4:junit4]   2> 2309 T1672 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2318 T1672 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:34231/solr
[junit4:junit4]   2> 2319 T1672 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34231/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@7a097a09
[junit4:junit4]   2> 2320 T1705 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:34231
[junit4:junit4]   2> 2320 T1672 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2321 T1705 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34231, initiating session
[junit4:junit4]   2> 2322 T1674 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52035
[junit4:junit4]   2> 2322 T1674 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52035
[junit4:junit4]   2> 2323 T1676 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aaa9c18ed0003 with negotiated timeout 5000 for client /127.0.0.1:52035
[junit4:junit4]   2> 2324 T1705 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:34231, sessionid = 0x13aaa9c18ed0003, negotiated timeout = 5000
[junit4:junit4]   2> 2324 T1706 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a097a09 name:ZooKeeperConnection Watcher:127.0.0.1:34231/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2325 T1672 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2326 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2327 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2327 T1672 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50827_solr
[junit4:junit4]   2> 2328 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50827_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50827_solr
[junit4:junit4]   2> 2329 T1672 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50827_solr
[junit4:junit4]   2> 2330 T1692 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2330 T1672 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2331 T1672 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2332 T1672 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2332 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2333 T1672 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2335 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2336 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2336 T1672 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2337 T1672 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2338 T1672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2344 T1672 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2348 T1672 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682/collection1
[junit4:junit4]   2> 2349 T1672 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2349 T1672 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2350 T1672 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2351 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682/collection1/'
[junit4:junit4]   2> 2352 T1672 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty1-1351482089682/collection1/lib/README' to classloader
[junit4:junit4]   2> 2353 T1672 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty1-1351482089682/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2386 T1672 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 2448 T1672 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2450 T1672 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2456 T1672 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2507 T1693 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2508 T1693 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:54839/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:54839_solr",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 2510 T1692 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2510 T1706 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2843 T1672 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2848 T1672 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2851 T1672 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2860 T1672 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2864 T1672 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2868 T1672 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2870 T1672 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2871 T1672 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351482089682/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty1/
[junit4:junit4]   2> 2871 T1672 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7550755
[junit4:junit4]   2> 2873 T1672 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty1/index/
[junit4:junit4]   2> 2874 T1672 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2875 T1672 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty1/index forceNew:false
[junit4:junit4]   2> 2882 T1672 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2883 T1672 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2884 T1672 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2887 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2887 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2888 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2889 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2890 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2890 T1672 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2891 T1672 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2892 T1672 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2893 T1672 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2894 T1672 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2895 T1672 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2896 T1672 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2897 T1672 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2897 T1672 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2898 T1672 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2899 T1672 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2900 T1672 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2901 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2902 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2903 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2904 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2905 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2906 T1672 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2907 T1672 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2908 T1672 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2910 T1672 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2911 T1672 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2913 T1672 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 2925 T1672 oass.SolrIndexSearcher.<init> Opening Searcher@19251925 main
[junit4:junit4]   2> 2927 T1672 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2927 T1672 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2929 T1672 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2935 T1672 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2976 T1707 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19251925 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2985 T1672 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3011 T1693 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3012 T1693 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50827/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50827_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 3013 T1693 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 3015 T1692 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3015 T1706 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3487 T1672 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3487 T1672 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:50827/solr shardId:shard1
[junit4:junit4]   2> 3489 T1672 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3492 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0003 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> 3493 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3494 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3498 T1672 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3499 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3499 T1672 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50827/solr/collection1/
[junit4:junit4]   2> 3500 T1672 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3501 T1672 oasc.SyncStrategy.syncToMe http://127.0.0.1:50827/solr/collection1/ has no replicas
[junit4:junit4]   2> 3501 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50827/solr/collection1/
[junit4:junit4]   2> 3502 T1672 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3504 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3516 T1693 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3519 T1692 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3519 T1706 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3555 T1672 oasc.ZkController.register We are http://127.0.0.1:50827/solr/collection1/ and leader is http://127.0.0.1:50827/solr/collection1/
[junit4:junit4]   2> 3556 T1672 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50827/solr
[junit4:junit4]   2> 3557 T1672 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3558 T1672 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3559 T1672 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3561 T1672 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3562 T1672 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3563 T1672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3851 T1672 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3853 T1672 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:40940
[junit4:junit4]   2> 3854 T1672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3855 T1672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221
[junit4:junit4]   2> 3855 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221/'
[junit4:junit4]   2> 3875 T1672 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3877 T1672 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3877 T1672 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221
[junit4:junit4]   2> 3878 T1672 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221/solr.xml
[junit4:junit4]   2> 3878 T1672 oasc.CoreContainer.<init> New CoreContainer 1225279752
[junit4:junit4]   2> 3879 T1672 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221/'
[junit4:junit4]   2> 3880 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221/'
[junit4:junit4]   2> 3894 T1672 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3904 T1672 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:34231/solr
[junit4:junit4]   2> 3905 T1672 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34231/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@2e652e65
[junit4:junit4]   2> 3906 T1717 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:34231
[junit4:junit4]   2> 3906 T1672 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3907 T1717 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34231, initiating session
[junit4:junit4]   2> 3907 T1674 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52036
[junit4:junit4]   2> 3908 T1674 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52036
[junit4:junit4]   2> 3909 T1676 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aaa9c18ed0004 with negotiated timeout 5000 for client /127.0.0.1:52036
[junit4:junit4]   2> 3909 T1717 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:34231, sessionid = 0x13aaa9c18ed0004, negotiated timeout = 5000
[junit4:junit4]   2> 3910 T1718 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2e652e65 name:ZooKeeperConnection Watcher:127.0.0.1:34231/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3910 T1672 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3911 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3912 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3913 T1672 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40940_solr
[junit4:junit4]   2> 3914 T1677 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aaa9c18ed0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:40940_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:40940_solr
[junit4:junit4]   2> 3914 T1672 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:40940_solr
[junit4:junit4]   2> 3918 T1692 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3918 T1706 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3918 T1672 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3920 T1672 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3920 T1672 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3921 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3921 T1672 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3922 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3923 T1672 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3923 T1672 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3924 T1672 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3925 T1672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3930 T1672 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3935 T1672 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221/collection1
[junit4:junit4]   2> 3935 T1672 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3936 T1672 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3937 T1672 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3938 T1672 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221/collection1/'
[junit4:junit4]   2> 3939 T1672 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty2-1351482091221/collection1/lib/README' to classloader
[junit4:junit4]   2> 3940 T1672 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty2-1351482091221/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3973 T1672 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 4021 T1693 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4022 T1693 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50827/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50827_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 4024 T1692 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4024 T1718 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4024 T1706 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4028 T1672 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4029 T1672 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4035 T1672 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4474 T1672 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4480 T1672 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4483 T1672 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4490 T1672 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4494 T1672 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4498 T1672 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4499 T1672 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4500 T1672 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351482091221/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty2/
[junit4:junit4]   2> 4500 T1672 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7550755
[junit4:junit4]   2> 4502 T1672 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty2/index/
[junit4:junit4]   2> 4503 T1672 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4504 T1672 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty2/index forceNew:false
[junit4:junit4]   2> 4509 T1672 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4509 T1672 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4511 T1672 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4513 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4514 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4515 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4515 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4516 T1672 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4517 T1672 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4518 T1672 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4519 T1672 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4520 T1672 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4521 T1672 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4522 T1672 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4522 T1672 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4523 T1672 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4524 T1672 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4525 T1672 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4526 T1672 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4527 T1672 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4528 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4529 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4530 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4531 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4532 T1672 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4533 T1672 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4534 T1672 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4535 T1672 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4536 T1672 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4538 T1672 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4539 T1672 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 4542 T1672 oass.SolrIndexSearcher.<init> Opening Searcher@4e174e17 main
[junit4:junit4]   2> 4543 T1672 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4544 T1672 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4545 T1672 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4560 T1672 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4600 T1719 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e174e17 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4609 T1672 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5027 T1693 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5029 T1693 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40940/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40940_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5032 T1718 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5032 T1692 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5032 T1706 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5112 T1672 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5113 T1672 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:40940/solr shardId:shard1
[junit4:junit4]   2> 5116 T1672 oasc.ZkController.register We are http://127.0.0.1:40940/solr/collection1/ and leader is http://127.0.0.1:50827/solr/collection1/
[junit4:junit4]   2> 5117 T1672 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:40940/solr
[junit4:junit4]   2> 5118 T1672 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 5120 T1672 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C66 name=collection1 org.apache.solr.core.SolrCore@67ef67ef url=http://127.0.0.1:40940/solr/collection1 node=127.0.0.1:40940_solr
[junit4:junit4]   2> 5121 T1720 C66 P40940 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 5122 T1720 C66 P40940 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 5121 T1672 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5122 T1720 C66 P40940 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5123 T1672 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5124 T1720 C66 P40940 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:50827/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 5124 T1672 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5125 T1720 C66 P40940 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:40940/solr START replicas=[http://127.0.0.1:50827/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 5126 T1672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5126 T1720 C66 P40940 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 5126 T1720 C66 P40940 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 5127 T1720 C66 P40940 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 5127 T1720 C66 P40940 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C67 name=collection1 org.apache.solr.core.SolrCore@56a956a9 url=http://127.0.0.1:50827/solr/collection1 node=127.0.0.1:50827_solr
[junit4:junit4]   2> 5130 T1702 C67 P50827 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 5130 T1672 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34231/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5ec25ec2
[junit4:junit4]   2> 5131 T1722 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:34231
[junit4:junit4]   2> 5131 T1672 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5132 T1722 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34231, initiating session
[junit4:junit4]   2> 5132 T1674 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52038
[junit4:junit4]   2> 5133 T1674 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52038
[junit4:junit4]   2> 5135 T1676 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aaa9c18ed0005 with negotiated timeout 10000 for client /127.0.0.1:52038
[junit4:junit4]   2> 5135 T1722 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:34231, sessionid = 0x13aaa9c18ed0005, negotiated timeout = 10000
[junit4:junit4]   2> 5136 T1723 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5ec25ec2 name:ZooKeeperConnection Watcher:127.0.0.1:34231/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5136 T1672 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5137 T1672 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5138 T1672 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 5138 T1672 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 5143 T1672 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C68 name=collection1 org.apache.solr.core.SolrCore@334c334c url=http://127.0.0.1:54839/solr/collection1 node=127.0.0.1:54839_solr
[junit4:junit4]   2> 5175 T1687 C68 P54839 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1351482087656/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5176 T1687 C68 P54839 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5180 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[1 (1417131686966067200)]} 0 8
[junit4:junit4]   2> 5180 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10001 (1417131686965018624)]} 0 9
[junit4:junit4]   2> 5181 T1724 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34231/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@643b643b
[junit4:junit4]   2> 5182 T1726 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:34231
[junit4:junit4]   2> 5182 T1724 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5183 T1726 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34231, initiating session
[junit4:junit4]   2> 5183 T1674 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52042
[junit4:junit4]   2> 5184 T1674 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52042
[junit4:junit4]   2> 5185 T1676 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aaa9c18ed0006 with negotiated timeout 10000 for client /127.0.0.1:52042
[junit4:junit4]   2> 5186 T1726 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:34231, sessionid = 0x13aaa9c18ed0006, negotiated timeout = 10000
[junit4:junit4]   2> 5186 T1727 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@643b643b name:ZooKeeperConnection Watcher:127.0.0.1:34231/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5187 T1724 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5188 T1724 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5193 T1703 C67 P50827 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5194 T1703 C67 P50827 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5199 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10001 (1417131686984941568)]} 0 9
[junit4:junit4]   2> 5199 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[1 (1417131686984941569)]} 0 9
[junit4:junit4]   2> 5202 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10002 (1417131686996475904)]} 0 1
[junit4:junit4]   2> 5203 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[2 (1417131686997524480)]} 0 1
[junit4:junit4]   2> 5206 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10002 (1417131687000670208)]} 0 1
[junit4:junit4]   2> 5206 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[2 (1417131687000670209)]} 0 1
[junit4:junit4]   2> 5209 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10001 (-1417131687003815936)]} 0 1
[junit4:junit4]   2> 5209 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[1 (-1417131687004864512)]} 0 0
[junit4:junit4]   2> 5211 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10001 (-1417131687006961664)]} 0 0
[junit4:junit4]   2> 5211 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[1 (-1417131687006961665)]} 0 0
[junit4:junit4]   2> 5214 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10003 (1417131687009058816)]} 0 1
[junit4:junit4]   2> 5215 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[3 (1417131687010107392)]} 0 1
[junit4:junit4]   2> 5217 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10003 (1417131687012204544)]} 0 1
[junit4:junit4]   2> 5218 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[3 (1417131687013253120)]} 0 1
[junit4:junit4]   2> 5220 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10004 (1417131687016398848)]} 0 1
[junit4:junit4]   2> 5221 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[4 (1417131687016398849)]} 0 1
[junit4:junit4]   2> 5223 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10004 (1417131687019544576)]} 0 1
[junit4:junit4]   2> 5224 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[4 (1417131687020593152)]} 0 0
[junit4:junit4]   2> 5226 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10002 (-1417131687022690304)]} 0 0
[junit4:junit4]   2> 5227 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[2 (-1417131687023738880)]} 0 0
[junit4:junit4]   2> 5228 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10002 (-1417131687024787456)]} 0 0
[junit4:junit4]   2> 5229 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[2 (-1417131687025836032)]} 0 0
[junit4:junit4]   2> 5231 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10005 (1417131687026884608)]} 0 1
[junit4:junit4]   2> 5232 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[5 (1417131687027933184)]} 0 1
[junit4:junit4]   2> 5234 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10005 (1417131687030030336)]} 0 1
[junit4:junit4]   2> 5235 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[5 (1417131687032127488)]} 0 0
[junit4:junit4]   2> 5237 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10006 (1417131687033176064)]} 0 1
[junit4:junit4]   2> 5246 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[6 (1417131687034224640)]} 0 9
[junit4:junit4]   2> 5249 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10006 (1417131687045758976)]} 0 1
[junit4:junit4]   2> 5249 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[6 (1417131687045758977)]} 0 1
[junit4:junit4]   2> 5252 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10007 (1417131687048904704)]} 0 1
[junit4:junit4]   2> 5254 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10007 (1417131687052050432)]} 0 0
[junit4:junit4]   2> 5257 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10004 (-1417131687055196160)]} 0 0
[junit4:junit4]   2> 5259 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10004 (-1417131687057293312)]} 0 0
[junit4:junit4]   2> 5261 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[7 (1417131687049953280)]} 0 9
[junit4:junit4]   2> 5263 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10008 (1417131687060439040)]} 0 1
[junit4:junit4]   2> 5264 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[7 (1417131687061487616)]} 0 1
[junit4:junit4]   2> 5265 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10008 (1417131687063584768)]} 0 0
[junit4:junit4]   2> 5266 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[4 (-1417131687064633344)]} 0 0
[junit4:junit4]   2> 5268 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[4 (-1417131687066730496)]} 0 0
[junit4:junit4]   2> 5269 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10009 (1417131687065681920)]} 0 2
[junit4:junit4]   2> 5271 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[8 (1417131687068827648)]} 0 1
[junit4:junit4]   2> 5272 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10009 (1417131687069876224)]} 0 1
[junit4:junit4]   2> 5273 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[8 (1417131687071973376)]} 0 0
[junit4:junit4]   2> 5274 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10009 (-1417131687073021952)]} 0 0
[junit4:junit4]   2> 5276 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[9 (1417131687075119104)]} 0 0
[junit4:junit4]   2> 5276 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10009 (-1417131687075119104)]} 0 0
[junit4:junit4]   2> 5279 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[9 (1417131687077216256)]} 0 1
[junit4:junit4]   2> 5279 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10010 (1417131687078264832)]} 0 0
[junit4:junit4]   2> 5281 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[9 (-1417131687080361984)]} 0 0
[junit4:junit4]   2> 5284 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[9 (-1417131687083507712)]} 0 0
[junit4:junit4]   2> 5289 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10 (1417131687087702016)]} 0 1
[junit4:junit4]   2> 5301 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10010 (1417131687082459136)]} 0 18
[junit4:junit4]   2> 5313 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10011 (1417131687105527808)]} 0 8
[junit4:junit4]   2> 5315 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10 (1417131687092944896)]} 0 22
[junit4:junit4]   2> 5317 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10011 (1417131687117062144)]} 0 1
[junit4:junit4]   2> 5318 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[11 (1417131687118110720)]} 0 1
[junit4:junit4]   2> 5320 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[10011 (-1417131687121256448)]} 0 0
[junit4:junit4]   2> 5321 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[11 (1417131687121256448)]} 0 1
[junit4:junit4]   2> 5322 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10011 (-1417131687123353600)]} 0 0
[junit4:junit4]   2> 5323 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[11 (-1417131687124402176)]} 0 0
[junit4:junit4]   2> 5325 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10012 (1417131687125450752)]} 0 1
[junit4:junit4]   2> 5325 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[11 (-1417131687126499328)]} 0 0
[junit4:junit4]   2> 5328 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10012 (1417131687128596480)]} 0 1
[junit4:junit4]   2> 5329 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[12 (1417131687130693632)]} 0 0
[junit4:junit4]   2> 5331 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10013 (1417131687132790784)]} 0 0
[junit4:junit4]   2> 5333 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[12 (1417131687132790784)]} 0 2
[junit4:junit4]   2> 5334 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10013 (1417131687134887936)]} 0 1
[junit4:junit4]   2> 5336 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[13 (1417131687136985088)]} 0 1
[junit4:junit4]   2> 5339 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[13 (1417131687140130816)]} 0 1
[junit4:junit4]   2> 5343 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[14 (1417131687143276544)]} 0 2
[junit4:junit4]   2> 5346 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[14 (1417131687147470848)]} 0 1
[junit4:junit4]   2> 5346 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10014 (1417131687139082240)]} 0 9
[junit4:junit4]   2> 5349 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[15 (1417131687150616576)]} 0 1
[junit4:junit4]   2> 5349 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10014 (1417131687151665152)]} 0 0
[junit4:junit4]   2> 5352 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[15 (1417131687153762304)]} 0 1
[junit4:junit4]   2> 5353 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10015 (1417131687154810880)]} 0 1
[junit4:junit4]   2> 5355 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[16 (1417131687156908032)]} 0 1
[junit4:junit4]   2> 5356 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10015 (1417131687157956608)]} 0 1
[junit4:junit4]   2> 5358 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[16 (1417131687160053760)]} 0 1
[junit4:junit4]   2> 5359 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10016 (1417131687161102336)]} 0 1
[junit4:junit4]   2> 5361 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[17 (1417131687163199488)]} 0 1
[junit4:junit4]   2> 5362 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10016 (1417131687164248064)]} 0 1
[junit4:junit4]   2> 5364 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[17 (1417131687166345216)]} 0 1
[junit4:junit4]   2> 5364 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10017 (1417131687167393792)]} 0 0
[junit4:junit4]   2> 5374 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[18 (1417131687169490944)]} 0 8
[junit4:junit4]   2> 5378 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[18 (1417131687181025280)]} 0 2
[junit4:junit4]   2> 5381 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[19 (1417131687185219584)]} 0 1
[junit4:junit4]   2> 5383 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10017 (1417131687177879552)]} 0 9
[junit4:junit4]   2> 5384 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[19 (1417131687187316736)]} 0 1
[junit4:junit4]   2> 5385 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10018 (1417131687189413888)]} 0 0
[junit4:junit4]   2> 5388 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10018 (1417131687191511040)]} 0 1
[junit4:junit4]   2> 5391 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10019 (1417131687194656768)]} 0 1
[junit4:junit4]   2> 5393 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10019 (1417131687197802496)]} 0 0
[junit4:junit4]   2> 5396 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[20 (1417131687190462464)]} 0 10
[junit4:junit4]   2> 5398 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[20 (1417131687203045376)]} 0 0
[junit4:junit4]   2> 5402 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[21 (1417131687205142528)]} 0 2
[junit4:junit4]   2> 5403 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10020 (1417131687199899648)]} 0 8
[junit4:junit4]   2> 5405 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[21 (1417131687209336832)]} 0 1
[junit4:junit4]   2> 5406 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10020 (1417131687210385408)]} 0 1
[junit4:junit4]   2> 5407 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[22 (1417131687212482560)]} 0 0
[junit4:junit4]   2> 5410 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10021 (1417131687213531136)]} 0 2
[junit4:junit4]   2> 5410 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[22 (1417131687215628288)]} 0 1
[junit4:junit4]   2> 5412 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10021 (1417131687217725440)]} 0 0
[junit4:junit4]   2> 5413 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[17 (-1417131687217725440)]} 0 1
[junit4:junit4]   2> 5423 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[17 (-1417131687229259776)]} 0 0
[junit4:junit4]   2> 5423 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10022 (1417131687229259776)]} 0 0
[junit4:junit4]   2> 5426 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[23 (1417131687231356928)]} 0 1
[junit4:junit4]   2> 5426 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10022 (1417131687232405504)]} 0 0
[junit4:junit4]   2> 5428 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[23 (1417131687234502656)]} 0 0
[junit4:junit4]   2> 5429 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10017 (-1417131687235551232)]} 0 0
[junit4:junit4]   2> 5431 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[19 (-1417131687237648384)]} 0 0
[junit4:junit4]   2> 5431 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10017 (-1417131687237648384)]} 0 0
[junit4:junit4]   2> 5433 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[19 (-1417131687239745536)]} 0 0
[junit4:junit4]   2> 5434 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10023 (1417131687239745536)]} 0 1
[junit4:junit4]   2> 5435 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[24 (1417131687241842688)]} 0 0
[junit4:junit4]   2> 5437 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10023 (1417131687242891264)]} 0 1
[junit4:junit4]   2> 5438 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[24 (1417131687244988416)]} 0 0
[junit4:junit4]   2> 5439 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10019 (-1417131687246036992)]} 0 0
[junit4:junit4]   2> 5440 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[21 (-1417131687247085568)]} 0 0
[junit4:junit4]   2> 5441 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10019 (-1417131687248134144)]} 0 0
[junit4:junit4]   2> 5443 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[21 (-1417131687250231296)]} 0 0
[junit4:junit4]   2> 5444 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10024 (1417131687250231296)]} 0 1
[junit4:junit4]   2> 5445 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[25 (1417131687252328448)]} 0 0
[junit4:junit4]   2> 5448 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[25 (1417131687255474176)]} 0 0
[junit4:junit4]   2> 5452 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[26 (1417131687258619904)]} 0 1
[junit4:junit4]   2> 5454 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10024 (1417131687253377024)]} 0 8
[junit4:junit4]   2> 5457 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[10021 (-1417131687264911360)]} 0 1
[junit4:junit4]   2> 5457 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[26 (1417131687261765632)]} 0 3
[junit4:junit4]   2> 5459 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10021 (-1417131687267008512)]} 0 0
[junit4:junit4]   2> 5460 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[27 (1417131687267008512)]} 0 1
[junit4:junit4]   2> 5463 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[27 (1417131687271202816)]} 0 0
[junit4:junit4]   2> 5466 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[22 (-1417131687274348544)]} 0 0
[junit4:junit4]   2> 5468 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[22 (-1417131687276445696)]} 0 0
[junit4:junit4]   2> 5470 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10025 (1417131687269105664)]} 0 9
[junit4:junit4]   2> 5473 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[28 (1417131687279591424)]} 0 2
[junit4:junit4]   2> 5476 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[28 (1417131687284834304)]} 0 0
[junit4:junit4]   2> 5480 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[29 (1417131687287980032)]} 0 1
[junit4:junit4]   2> 5481 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10025 (1417131687280640000)]} 0 9
[junit4:junit4]   2> 5483 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[29 (1417131687291125760)]} 0 1
[junit4:junit4]   2> 5484 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10026 (1417131687292174336)]} 0 1
[junit4:junit4]   2> 5485 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[23 (-1417131687294271488)]} 0 0
[junit4:junit4]   2> 5487 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[23 (-1417131687296368640)]} 0 0
[junit4:junit4]   2> 5487 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10026 (1417131687295320064)]} 0 1
[junit4:junit4]   2> 5490 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[30 (1417131687298465792)]} 0 1
[junit4:junit4]   2> 5501 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10027 (1417131687299514368)]} 0 11
[junit4:junit4]   2> 5503 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[30 (1417131687313145856)]} 0 0
[junit4:junit4]   2> 5506 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10027 (1417131687314194432)]} 0 2
[junit4:junit4]   2> 5506 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[31 (1417131687315243008)]} 0 1
[junit4:junit4]   2> 5508 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10022 (-1417131687318388736)]} 0 0
[junit4:junit4]   2> 5509 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[31 (1417131687318388736)]} 0 1
[junit4:junit4]   2> 5510 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10022 (-1417131687320485888)]} 0 0
[junit4:junit4]   2> 5511 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[32 (1417131687321534464)]} 0 0
[junit4:junit4]   2> 5513 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10028 (1417131687322583040)]} 0 1
[junit4:junit4]   2> 5514 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[32 (1417131687324680192)]} 0 0
[junit4:junit4]   2> 5515 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10028 (1417131687325728768)]} 0 0
[junit4:junit4]   2> 5516 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[26 (-1417131687326777344)]} 0 0
[junit4:junit4]   2> 5518 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10029 (1417131687327825920)]} 0 1
[junit4:junit4]   2> 5518 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[26 (-1417131687328874496)]} 0 0
[junit4:junit4]   2> 5520 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10029 (1417131687330971648)]} 0 0
[junit4:junit4]   2> 5521 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[33 (1417131687332020224)]} 0 0
[junit4:junit4]   2> 5523 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10023 (-1417131687333068800)]} 0 1
[junit4:junit4]   2> 5524 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[33 (1417131687334117376)]} 0 1
[junit4:junit4]   2> 5525 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10023 (-1417131687336214528)]} 0 0
[junit4:junit4]   2> 5526 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[34 (1417131687337263104)]} 0 0
[junit4:junit4]   2> 5527 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10030 (1417131687338311680)]} 0 0
[junit4:junit4]   2> 5529 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[34 (1417131687339360256)]} 0 1
[junit4:junit4]   2> 5534 T1693 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5535 T1693 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40940/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40940_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5537 T1692 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5537 T1723 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5537 T1706 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5537 T1718 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5537 T1727 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5540 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[35 (1417131687342505984)]} 0 9
[junit4:junit4]   2> 5540 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10030 (1417131687340408832)]} 0 11
[junit4:junit4]   2> 5544 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10031 (1417131687355088896)]} 0 1
[junit4:junit4]   2>  C66_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:40940/solr, state=recovering, node_name=127.0.0.1:40940_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 5554 T1714 C66 P40940 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1351482087656/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5555 T1714 C66 P40940 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5558 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=8 
[junit4:junit4]   2> 5559 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=6 
[junit4:junit4]   2> 5559 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[35 (1417131687354040320)]} 0 17
[junit4:junit4]   2> 5560 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10031 (1417131687359283200)]} 0 13
[junit4:junit4]   2> 5562 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[36 (1417131687375011840)]} 0 0
[junit4:junit4]   2> 5563 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10032 (1417131687375011841)]} 0 1
[junit4:junit4]   2> 5575 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5576 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5576 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[36 (1417131687378157568)]} 0 11
[junit4:junit4]   2> 5577 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10032 (1417131687386546176)]} 0 4
[junit4:junit4]   2> 5580 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[37 (1417131687392837632)]} 0 1
[junit4:junit4]   2> 5580 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10026 (-1417131687393886208)]} 0 0
[junit4:junit4]   2> 5584 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5584 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5585 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10026 (-1417131687397031936)]} 0 3
[junit4:junit4]   2> 5586 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[37 (1417131687395983360)]} 0 4
[junit4:junit4]   2> 5589 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10033 (1417131687402274816)]} 0 1
[junit4:junit4]   2> 5589 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[38 (1417131687403323392)]} 0 0
[junit4:junit4]   2> 5594 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5594 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5595 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10033 (1417131687405420544)]} 0 4
[junit4:junit4]   2> 5595 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[38 (1417131687406469120)]} 0 3
[junit4:junit4]   2> 5598 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10034 (1417131687412760576)]} 0 0
[junit4:junit4]   2> 5599 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[28 (-1417131687413809152)]} 0 0
[junit4:junit4]   2> 5604 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5604 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5605 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[28 (-1417131687416954880)]} 0 3
[junit4:junit4]   2> 5607 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10034 (1417131687415906304)]} 0 6
[junit4:junit4]   2> 5611 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[39 (1417131687426392064)]} 0 0
[junit4:junit4]   2> 5613 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10035 (1417131687427440640)]} 0 1
[junit4:junit4]   2> 5618 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5619 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[39 (1417131687430586368)]} 0 4
[junit4:junit4]   2> 5623 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[29 (-1417131687438974976)]} 0 0
[junit4:junit4]   2> 5628 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5630 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[29 (-1417131687442120704)]} 0 4
[junit4:junit4]   2> 5634 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[40 (1417131687449460736)]} 0 1
[junit4:junit4]   2> 5635 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=16 
[junit4:junit4]   2> 5637 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10035 (1417131687431634944)]} 0 21
[junit4:junit4]   2> 5641 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10036 (1417131687456800768)]} 0 1
[junit4:junit4]   2> 5643 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 5645 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[40 (1417131687453655040)]} 0 8
[junit4:junit4]   2> 5647 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5648 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[30 (-1417131687465189376)]} 0 0
[junit4:junit4]   2> 5649 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10036 (1417131687460995072)]} 0 5
[junit4:junit4]   2> 5654 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5654 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10037 (1417131687471480832)]} 0 0
[junit4:junit4]   2> 5656 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[30 (-1417131687469383680)]} 0 4
[junit4:junit4]   2> 5662 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5664 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10037 (1417131687476723712)]} 0 5
[junit4:junit4]   2> 5671 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10038 (1417131687485112320)]} 0 4
[junit4:junit4]   2> 5677 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[41 (1417131687478820864)]} 0 16
[junit4:junit4]   2> 5678 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 5680 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10038 (1417131687492452352)]} 0 6
[junit4:junit4]   2> 5684 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[10028 (-1417131687502938112)]} 0 0
[junit4:junit4]   2> 5690 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5691 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10028 (-1417131687507132416)]} 0 3
[junit4:junit4]   2> 5694 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5694 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10039 (1417131687512375296)]} 0 1
[junit4:junit4]   2> 5695 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[41 (1417131687498743808)]} 0 15
[junit4:junit4]   2> 5698 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[42 (1417131687516569600)]} 0 1
[junit4:junit4]   2> 5710 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5711 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10039 (1417131687516569600)]} 0 14
[junit4:junit4]   2> 5713 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10029 (-1417131687533346816)]} 0 0
[junit4:junit4]   2> 5717 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5718 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10029 (-1417131687536492544)]} 0 2
[junit4:junit4]   2> 5721 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10040 (1417131687540686848)]} 0 1
[junit4:junit4]   2> 5726 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5726 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10040 (1417131687544881152)]} 0 3
[junit4:junit4]   2> 5729 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10030 (-1417131687550124032)]} 0 0
[junit4:junit4]   2> 5732 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5733 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10030 (-1417131687552221184)]} 0 2
[junit4:junit4]   2> 5736 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10041 (1417131687556415488)]} 0 1
[junit4:junit4]   2> 5740 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5741 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10041 (1417131687559561216)]} 0 3
[junit4:junit4]   2> 5743 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10042 (1417131687564804096)]} 0 0
[junit4:junit4]   2> 5748 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5748 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10042 (1417131687567949824)]} 0 2
[junit4:junit4]   2> 5751 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[10031 (-1417131687573192704)]} 0 0
[junit4:junit4]   2> 5754 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5756 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10031 (-1417131687575289856)]} 0 3
[junit4:junit4]   2> 5772 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 5773 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[42 (1417131687530201088)]} 0 63
[junit4:junit4]   2> 5775 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[31 (-1417131687598358528)]} 0 0
[junit4:junit4]   2> 5779 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5789 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[31 (-1417131687601504256)]} 0 11
[junit4:junit4]   2> 5792 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[43 (1417131687615135744)]} 0 1
[junit4:junit4]   2> 5798 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 5799 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[43 (1417131687618281472)]} 0 5
[junit4:junit4]   2> 5802 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[33 (-1417131687626670080)]} 0 0
[junit4:junit4]   2> 5805 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5807 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[33 (-1417131687628767232)]} 0 3
[junit4:junit4]   2> 5809 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[44 (1417131687634010112)]} 0 0
[junit4:junit4]   2> 5814 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5815 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[44 (1417131687636107264)]} 0 4
[junit4:junit4]   2> 5818 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[45 (1417131687643447296)]} 0 0
[junit4:junit4]   2> 5819 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10043 (1417131687580532736)]} 0 61
[junit4:junit4]   2> 5823 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5824 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[45 (1417131687646593024)]} 0 3
[junit4:junit4]   2> 5826 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5827 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10043 (1417131687647641600)]} 0 5
[junit4:junit4]   2> 5827 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[46 (1417131687652884480)]} 0 0
[junit4:junit4]   2> 5830 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10033 (-1417131687656030208)]} 0 0
[junit4:junit4]   2> 5834 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5834 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5835 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[46 (1417131687657078784)]} 0 4
[junit4:junit4]   2> 5836 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10033 (-1417131687659175936)]} 0 3
[junit4:junit4]   2> 5841 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10044 (1417131687665467392)]} 0 2
[junit4:junit4]   2> 5846 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5846 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10044 (1417131687669661696)]} 0 3
[junit4:junit4]   2> 5858 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10045 (1417131687683293184)]} 0 2
[junit4:junit4]   2> 5862 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5863 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10045 (1417131687687487488)]} 0 3
[junit4:junit4]   2> 5866 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10046 (1417131687692730368)]} 0 1
[junit4:junit4]   2> 5870 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[47 (1417131687664418816)]} 0 32
[junit4:junit4]   2> 5874 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5875 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[47 (1417131687700070400)]} 0 3
[junit4:junit4]   2> 5877 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[48 (1417131687705313280)]} 0 0
[junit4:junit4]   2> 5878 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5879 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10046 (1417131687695876096)]} 0 11
[junit4:junit4]   2> 5881 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10047 (1417131687709507584)]} 0 0
[junit4:junit4]   2> 5888 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 5889 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10047 (1417131687711604736)]} 0 6
[junit4:junit4]   2> 5892 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10048 (1417131687719993344)]} 0 1
[junit4:junit4]   2> 5893 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=10 
[junit4:junit4]   2> 5895 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[48 (1417131687707410432)]} 0 16
[junit4:junit4]   2> 5897 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[36 (-1417131687726284800)]} 0 0
[junit4:junit4]   2> 5898 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5899 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10048 (1417131687724187648)]} 0 4
[junit4:junit4]   2> 5901 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5902 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10036 (-1417131687731527680)]} 0 0
[junit4:junit4]   2> 5902 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[36 (-1417131687729430528)]} 0 2
[junit4:junit4]   2> 5906 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[49 (1417131687734673408)]} 0 1
[junit4:junit4]   2> 5906 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5909 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10036 (-1417131687733624832)]} 0 5
[junit4:junit4]   2> 5911 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5911 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10049 (1417131687740964864)]} 0 0
[junit4:junit4]   2> 5912 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[49 (1417131687738867712)]} 0 3
[junit4:junit4]   2> 5915 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[39 (-1417131687745159168)]} 0 0
[junit4:junit4]   2> 5916 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5917 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10049 (1417131687744110592)]} 0 3
[junit4:junit4]   2> 5919 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5920 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10039 (-1417131687750402048)]} 0 0
[junit4:junit4]   2> 5920 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[39 (-1417131687747256320)]} 0 3
[junit4:junit4]   2> 5924 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5924 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[50 (1417131687754596352)]} 0 0
[junit4:junit4]   2> 5925 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10039 (-1417131687752499200)]} 0 3
[junit4:junit4]   2> 5928 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10050 (1417131687757742080)]} 0 1
[junit4:junit4]   2> 5929 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5930 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[50 (1417131687757742080)]} 0 3
[junit4:junit4]   2> 5932 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[40 (-1417131687762984960)]} 0 0
[junit4:junit4]   2> 5932 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5933 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10050 (1417131687760887808)]} 0 3
[junit4:junit4]   2> 5935 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10040 (-1417131687766130688)]} 0 0
[junit4:junit4]   2> 5935 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5937 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[40 (-1417131687765082112)]} 0 3
[junit4:junit4]   2> 5939 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5939 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[51 (1417131687770324992)]} 0 0
[junit4:junit4]   2> 5940 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10040 (-1417131687768227840)]} 0 3
[junit4:junit4]   2> 5944 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5944 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[51 (1417131687772422144)]} 0 3
[junit4:junit4]   2> 5947 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[41 (-1417131687778713600)]} 0 0
[junit4:junit4]   2> 5950 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10051 (1417131687773470720)]} 0 8
[junit4:junit4]   2> 5951 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5951 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[41 (-1417131687780810752)]} 0 2
[junit4:junit4]   2> 5954 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[52 (1417131687786053632)]} 0 0
[junit4:junit4]   2> 5962 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=8 
[junit4:junit4]   2> 5963 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10051 (1417131687783956480)]} 0 11
[junit4:junit4]   2> 5965 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[10041 (-1417131687797587968)]} 0 0
[junit4:junit4]   2> 5966 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5967 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[52 (1417131687796539392)]} 0 3
[junit4:junit4]   2> 5969 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5970 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[45 (-1417131687801782272)]} 0 1
[junit4:junit4]   2> 5970 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10041 (-1417131687800733696)]} 0 2
[junit4:junit4]   2> 5974 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 5974 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[45 (-1417131687804928000)]} 0 2
[junit4:junit4]   2> 5975 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10052 (1417131687805976576)]} 0 2
[junit4:junit4]   2> 5977 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[53 (1417131687809122304)]} 0 1
[junit4:junit4]   2> 5983 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 5985 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[53 (1417131687812268032)]} 0 6
[junit4:junit4]   2> 5987 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[47 (-1417131687820656640)]} 0 0
[junit4:junit4]   2> 5989 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=9 
[junit4:junit4]   2> 5989 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10052 (1417131687811219456)]} 0 11
[junit4:junit4]   2> 5991 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5992 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10045 (-1417131687825899520)]} 0 1
[junit4:junit4]   2> 5992 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[47 (-1417131687823802368)]} 0 3
[junit4:junit4]   2> 5995 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[54 (1417131687827996672)]} 0 1
[junit4:junit4]   2> 5995 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 5997 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10045 (-1417131687827996672)]} 0 3
[junit4:junit4]   2> 5999 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10053 (1417131687833239552)]} 0 0
[junit4:junit4]   2> 6000 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6002 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[54 (1417131687832190976)]} 0 5
[junit4:junit4]   2> 6004 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[49 (-1417131687838482432)]} 0 0
[junit4:junit4]   2> 6004 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6006 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10053 (1417131687836385280)]} 0 4
[junit4:junit4]   2> 6008 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6008 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10047 (-1417131687842676736)]} 0 0
[junit4:junit4]   2> 6009 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[49 (-1417131687841628160)]} 0 2
[junit4:junit4]   2> 6012 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[55 (1417131687846871040)]} 0 0
[junit4:junit4]   2> 6013 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6014 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10047 (-1417131687845822464)]} 0 3
[junit4:junit4]   2> 6016 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10054 (1417131687851065344)]} 0 0
[junit4:junit4]   2> 6022 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6023 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10054 (1417131687853162496)]} 0 5
[junit4:junit4]   2> 6025 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10049 (-1417131687860502528)]} 0 0
[junit4:junit4]   2> 6029 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6029 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6030 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10049 (-1417131687862599680)]} 0 3
[junit4:junit4]   2> 6030 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[55 (1417131687848968192)]} 0 16
[junit4:junit4]   2> 6032 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10055 (1417131687867842560)]} 0 0
[junit4:junit4]   2> 6033 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[50 (-1417131687868891136)]} 0 0
[junit4:junit4]   2> 6036 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6037 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6037 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10055 (1417131687869939712)]} 0 3
[junit4:junit4]   2> 6038 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[50 (-1417131687870988288)]} 0 3
[junit4:junit4]   2> 6040 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10050 (-1417131687876231168)]} 0 0
[junit4:junit4]   2> 6041 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[56 (1417131687876231169)]} 0 1
[junit4:junit4]   2> 6044 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6044 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10050 (-1417131687878328320)]} 0 2
[junit4:junit4]   2> 6045 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6046 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[56 (1417131687879376896)]} 0 3
[junit4:junit4]   2> 6047 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10056 (1417131687883571200)]} 0 0
[junit4:junit4]   2> 6049 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[57 (1417131687885668352)]} 0 0
[junit4:junit4]   2> 6052 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6053 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10056 (1417131687886716928)]} 0 3
[junit4:junit4]   2> 6054 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6056 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[57 (1417131687887765504)]} 0 5
[junit4:junit4]   2> 6057 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10057 (1417131687893008384)]} 0 1
[junit4:junit4]   2> 6059 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[52 (-1417131687896154112)]} 0 0
[junit4:junit4]   2> 6062 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6063 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[52 (-1417131687898251264)]} 0 2
[junit4:junit4]   2> 6067 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[58 (1417131687903494144)]} 0 1
[junit4:junit4]   2> 6090 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=29 
[junit4:junit4]   2> 6091 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10057 (1417131687897202688)]} 0 31
[junit4:junit4]   2> 6092 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6094 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[58 (1417131687908737024)]} 0 23
[junit4:junit4]   2> 6094 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10052 (-1417131687932854272)]} 0 0
[junit4:junit4]   2> 6097 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[56 (-1417131687936000000)]} 0 0
[junit4:junit4]   2> 6100 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6101 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10052 (-1417131687937048576)]} 0 3
[junit4:junit4]   2> 6101 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6103 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[56 (-1417131687939145728)]} 0 3
[junit4:junit4]   2> 6109 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[59 (1417131687945437184)]} 0 3
[junit4:junit4]   2> 6115 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6117 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[59 (1417131687952777216)]} 0 4
[junit4:junit4]   2> 6119 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10058 (1417131687943340032)]} 0 16
[junit4:junit4]   2> 6120 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[60 (1417131687960117248)]} 0 0
[junit4:junit4]   2> 6126 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6128 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[60 (1417131687963262976)]} 0 5
[junit4:junit4]   2> 6132 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[61 (1417131687971651584)]} 0 1
[junit4:junit4]   2> 6138 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6140 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[61 (1417131687975845888)]} 0 5
[junit4:junit4]   2> 6141 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6143 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10058 (1417131687961165824)]} 0 22
[junit4:junit4]   2> 6144 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[62 (1417131687984234496)]} 0 1
[junit4:junit4]   2> 6147 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[10056 (-1417131687988428800)]} 0 0
[junit4:junit4]   2> 6152 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6154 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10056 (-1417131687991574528)]} 0 4
[junit4:junit4]   2> 6158 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10059 (1417131687998914560)]} 0 1
[junit4:junit4]   2> 6167 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6169 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10059 (1417131688003108864)]} 0 8
[junit4:junit4]   2> 6170 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6172 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[62 (1417131687989477376)]} 0 24
[junit4:junit4]   2> 6173 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10060 (1417131688014643200)]} 0 1
[junit4:junit4]   2> 6175 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[58 (-1417131688017788928)]} 0 0
[junit4:junit4]   2> 6180 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6182 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[58 (-1417131688020934656)]} 0 4
[junit4:junit4]   2> 6182 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6185 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10060 (1417131688018837504)]} 0 9
[junit4:junit4]   2> 6186 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[63 (1417131688028274688)]} 0 1
[junit4:junit4]   2> 6189 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10061 (1417131688031420416)]} 0 1
[junit4:junit4]   2> 6193 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6194 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[63 (1417131688033517568)]} 0 4
[junit4:junit4]   2> 6198 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[64 (1417131688041906176)]} 0 0
[junit4:junit4]   2> 6207 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 6208 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[64 (1417131688046100480)]} 0 6
[junit4:junit4]   2> 6209 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=15 
[junit4:junit4]   2> 6210 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10061 (1417131688035614720)]} 0 18
[junit4:junit4]   2> 6211 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[65 (1417131688054489088)]} 0 1
[junit4:junit4]   2> 6213 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10062 (1417131688056586240)]} 0 1
[junit4:junit4]   2> 6226 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=10 
[junit4:junit4]   2> 6227 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[65 (1417131688057634816)]} 0 14
[junit4:junit4]   2> 6228 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6229 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10062 (1417131688071266304)]} 0 3
[junit4:junit4]   2> 6229 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[59 (-1417131688074412032)]} 0 0
[junit4:junit4]   2> 6231 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10058 (-1417131688076509184)]} 0 0
[junit4:junit4]   2> 6233 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6234 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[59 (-1417131688077557760)]} 0 2
[junit4:junit4]   2> 6235 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6236 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[10058 (-1417131688079654912)]} 0 2
[junit4:junit4]   2> 6237 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[66 (1417131688081752064)]} 0 1
[junit4:junit4]   2> 6241 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6242 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[66 (1417131688084897792)]} 0 3
[junit4:junit4]   2> 6244 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[60 (-1417131688090140672)]} 0 0
[junit4:junit4]   2> 6247 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10063 (1417131688083849216)]} 0 9
[junit4:junit4]   2> 6248 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6249 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[60 (-1417131688093286400)]} 0 2
[junit4:junit4]   2> 6252 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6253 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10063 (1417131688095383552)]} 0 4
[junit4:junit4]   2> 6254 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[67 (1417131688097480704)]} 0 3
[junit4:junit4]   2> 6256 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10064 (1417131688102723584)]} 0 1
[junit4:junit4]   2> 6258 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6259 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[67 (1417131688102723584)]} 0 3
[junit4:junit4]   2> 6261 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6262 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10064 (1417131688104820736)]} 0 4
[junit4:junit4]   2> 6265 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10065 (1417131688111112192)]} 0 1
[junit4:junit4]   2> 6269 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6270 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10065 (1417131688114257920)]} 0 3
[junit4:junit4]   2> 6271 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[68 (1417131688107966464)]} 0 10
[junit4:junit4]   2> 6272 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10059 (-1417131688119500800)]} 0 0
[junit4:junit4]   2> 6276 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6276 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6276 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[68 (1417131688121597952)]} 0 2
[junit4:junit4]   2> 6277 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[10059 (-1417131688122646528)]} 0 2
[junit4:junit4]   2> 6279 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[64 (-1417131688126840832)]} 0 0
[junit4:junit4]   2> 6280 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10066 (1417131688127889408)]} 0 0
[junit4:junit4]   2> 6284 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6284 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[64 (-1417131688129986560)]} 0 2
[junit4:junit4]   2> 6289 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[69 (1417131688134180864)]} 0 3
[junit4:junit4]   2> 6298 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6299 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[69 (1417131688140472320)]} 0 7
[junit4:junit4]   2> 6301 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[70 (1417131688149909504)]} 0 0
[junit4:junit4]   2> 6305 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=13 
[junit4:junit4]   2> 6306 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6307 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10066 (1417131688129986561)]} 0 25
[junit4:junit4]   2> 6307 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[70 (1417131688153055232)]} 0 4
[junit4:junit4]   2> 6309 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10060 (-1417131688158298112)]} 0 0
[junit4:junit4]   2> 6310 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[71 (1417131688159346688)]} 0 0
[junit4:junit4]   2> 6313 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6314 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10060 (-1417131688160395264)]} 0 3
[junit4:junit4]   2> 6316 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6317 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[71 (1417131688162492416)]} 0 4
[junit4:junit4]   2> 6327 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10067 (1417131688166686720)]} 0 10
[junit4:junit4]   2> 6329 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[65 (-1417131688179269632)]} 0 1
[junit4:junit4]   2> 6331 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6332 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10067 (1417131688179269632)]} 0 3
[junit4:junit4]   2> 6332 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6334 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[65 (-1417131688181366784)]} 0 3
[junit4:junit4]   2> 6335 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10068 (1417131688184512512)]} 0 1
[junit4:junit4]   2> 6336 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[72 (1417131688186609664)]} 0 0
[junit4:junit4]   2> 6339 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6340 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10068 (1417131688187658240)]} 0 3
[junit4:junit4]   2> 6340 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6341 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[72 (1417131688188706816)]} 0 3
[junit4:junit4]   2> 6342 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10064 (-1417131688192901120)]} 0 0
[junit4:junit4]   2> 6344 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[73 (1417131688193949696)]} 0 1
[junit4:junit4]   2> 6346 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6347 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10064 (-1417131688196046848)]} 0 2
[junit4:junit4]   2> 6348 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6349 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[73 (1417131688197095424)]} 0 3
[junit4:junit4]   2> 6351 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[67 (-1417131688202338304)]} 0 0
[junit4:junit4]   2> 6355 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6355 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[67 (-1417131688204435456)]} 0 2
[junit4:junit4]   2> 6358 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[74 (1417131688209678336)]} 0 0
[junit4:junit4]   2> 6359 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10069 (1417131688200241152)]} 0 10
[junit4:junit4]   2> 6363 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6364 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6364 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[74 (1417131688212824064)]} 0 3
[junit4:junit4]   2> 6365 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10069 (1417131688212824065)]} 0 4
[junit4:junit4]   2> 6367 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[68 (-1417131688219115520)]} 0 0
[junit4:junit4]   2> 6369 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10070 (1417131688219115521)]} 0 2
[junit4:junit4]   2> 6370 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6371 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[68 (-1417131688221212672)]} 0 2
[junit4:junit4]   2> 6374 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[75 (1417131688225406976)]} 0 1
[junit4:junit4]   2> 6387 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6388 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[75 (1417131688228552704)]} 0 12
[junit4:junit4]   2> 6391 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[76 (1417131688243232768)]} 0 1
[junit4:junit4]   2> 6396 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6398 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[76 (1417131688246378496)]} 0 5
[junit4:junit4]   2> 6400 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[77 (1417131688253718528)]} 0 0
[junit4:junit4]   2> 6404 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6405 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[77 (1417131688255815680)]} 0 3
[junit4:junit4]   2> 6408 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[78 (1417131688261058560)]} 0 1
[junit4:junit4]   2> 6422 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6422 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[78 (1417131688274690048)]} 0 3
[junit4:junit4]   2> 6424 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=51 
[junit4:junit4]   2> 6425 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[79 (1417131688278884352)]} 0 1
[junit4:junit4]   2> 6425 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10070 (1417131688223309824)]} 0 54
[junit4:junit4]   2> 6428 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10071 (1417131688283078656)]} 0 0
[junit4:junit4]   2> 6429 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6430 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[79 (1417131688282030080)]} 0 3
[junit4:junit4]   2> 6432 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6433 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[80 (1417131688287272960)]} 0 1
[junit4:junit4]   2> 6433 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10071 (1417131688285175808)]} 0 3
[junit4:junit4]   2> 6436 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10065 (-1417131688291467264)]} 0 0
[junit4:junit4]   2> 6437 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6439 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[80 (1417131688290418688)]} 0 4
[junit4:junit4]   2> 6440 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6441 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[10065 (-1417131688293564416)]} 0 3
[junit4:junit4]   2> 6441 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[69 (-1417131688296710144)]} 0 0
[junit4:junit4]   2> 6443 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10072 (1417131688298807296)]} 0 0
[junit4:junit4]   2> 6445 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6446 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[69 (-1417131688298807296)]} 0 3
[junit4:junit4]   2> 6448 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6448 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[81 (1417131688304050176)]} 0 0
[junit4:junit4]   2> 6449 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10072 (1417131688300904448)]} 0 4
[junit4:junit4]   2> 6452 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10073 (1417131688308244480)]} 0 0
[junit4:junit4]   2> 6453 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6454 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[81 (1417131688307195904)]} 0 3
[junit4:junit4]   2> 6457 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[72 (-1417131688313487360)]} 0 0
[junit4:junit4]   2> 6461 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6462 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[72 (-1417131688316633088)]} 0 3
[junit4:junit4]   2> 6469 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=13 
[junit4:junit4]   2> 6470 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10073 (1417131688310341632)]} 0 16
[junit4:junit4]   2> 6473 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10067 (-1417131688330264576)]} 0 0
[junit4:junit4]   2> 6485 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6486 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10067 (-1417131688332361728)]} 0 11
[junit4:junit4]   2> 6491 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10074 (1417131688345993216)]} 0 3
[junit4:junit4]   2> 6497 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6498 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10074 (1417131688352284672)]} 0 4
[junit4:junit4]   2> 6500 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[10068 (-1417131688358576128)]} 0 0
[junit4:junit4]   2> 6504 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6505 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10068 (-1417131688361721856)]} 0 3
[junit4:junit4]   2> 6508 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10075 (1417131688365916160)]} 0 1
[junit4:junit4]   2> 6512 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6513 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10075 (1417131688369061888)]} 0 3
[junit4:junit4]   2> 6517 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10076 (1417131688375353344)]} 0 1
[junit4:junit4]   2> 6519 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[82 (1417131688320827392)]} 0 55
[junit4:junit4]   2> 6522 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6523 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10076 (1417131688378499072)]} 0 4
[junit4:junit4]   2> 6524 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6525 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[82 (1417131688380596224)]} 0 4
[junit4:junit4]   2> 6526 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10077 (1417131688384790528)]} 0 1
[junit4:junit4]   2> 6530 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[74 (-1417131688390033408)]} 0 0
[junit4:junit4]   2> 6534 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6535 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[74 (-1417131688393179136)]} 0 2
[junit4:junit4]   2> 6539 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[83 (1417131688398422016)]} 0 1
[junit4:junit4]   2> 6609 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6611 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10077 (1417131688391081984)]} 0 80
[junit4:junit4]   2> 6614 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6614 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10078 (1417131688477065216)]} 0 1
[junit4:junit4]   2> 6615 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[83 (1417131688401567744)]} 0 74
[junit4:junit4]   2> 6618 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[75 (-1417131688482308096)]} 0 0
[junit4:junit4]   2> 6621 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6622 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10078 (1417131688481259520)]} 0 5
[junit4:junit4]   2> 6622 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6624 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[75 (-1417131688485453824)]} 0 3
[junit4:junit4]   2> 6626 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10079 (1417131688489648128)]} 0 1
[junit4:junit4]   2> 6627 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[84 (1417131688491745280)]} 0 0
[junit4:junit4]   2> 6630 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6631 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10079 (1417131688492793856)]} 0 3
[junit4:junit4]   2> 6634 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6634 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10080 (1417131688499085312)]} 0 0
[junit4:junit4]   2> 6635 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[84 (1417131688494891008)]} 0 5
[junit4:junit4]   2> 6638 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[77 (-1417131688503279616)]} 0 0
[junit4:junit4]   2> 6643 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6644 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[77 (-1417131688506425344)]} 0 3
[junit4:junit4]   2> 6657 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[85 (1417131688522153984)]} 0 1
[junit4:junit4]   2> 6661 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=22 
[junit4:junit4]   2> 6662 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10080 (1417131688502231040)]} 0 25
[junit4:junit4]   2> 6663 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6664 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[85 (1417131688525299712)]} 0 5
[junit4:junit4]   2> 6665 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10069 (-1417131688531591168)]} 0 0
[junit4:junit4]   2> 6667 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[86 (1417131688533688320)]} 0 0
[junit4:junit4]   2> 6669 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6670 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10069 (-1417131688534736896)]} 0 2
[junit4:junit4]   2> 6672 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6673 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[86 (1417131688535785472)]} 0 4
[junit4:junit4]   2> 6673 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10081 (1417131688539979776)]} 0 0
[junit4:junit4]   2> 6676 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[78 (-1417131688543125504)]} 0 0
[junit4:junit4]   2> 6678 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6679 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10081 (1417131688543125504)]} 0 3
[junit4:junit4]   2> 6680 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6681 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[78 (-1417131688546271232)]} 0 2
[junit4:junit4]   2> 6681 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10072 (-1417131688548368384)]} 0 0
[junit4:junit4]   2> 6686 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6687 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10072 (-1417131688551514112)]} 0 3
[junit4:junit4]   2> 6692 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10082 (1417131688556756992)]} 0 3
[junit4:junit4]   2> 6692 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[87 (1417131688550465536)]} 0 9
[junit4:junit4]   2> 6697 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6697 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6698 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10082 (1417131688561999872)]} 0 4
[junit4:junit4]   2> 6698 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[87 (1417131688563048448)]} 0 3
[junit4:junit4]   2> 6701 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[10074 (-1417131688568291328)]} 0 1
[junit4:junit4]   2> 6702 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[88 (1417131688569339904)]} 0 1
[junit4:junit4]   2> 6705 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6706 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10074 (-1417131688571437056)]} 0 3
[junit4:junit4]   2> 6706 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6708 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[88 (1417131688572485632)]} 0 4
[junit4:junit4]   2> 6708 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10083 (1417131688576679936)]} 0 0
[junit4:junit4]   2> 6710 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[89 (1417131688578777088)]} 0 0
[junit4:junit4]   2> 6717 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6718 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[89 (1417131688581922816)]} 0 5
[junit4:junit4]   2> 6721 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[90 (1417131688589262848)]} 0 1
[junit4:junit4]   2> 6722 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6724 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10083 (1417131688579825664)]} 0 13
[junit4:junit4]   2> 6725 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6726 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10075 (-1417131688595554304)]} 0 0
[junit4:junit4]   2> 6727 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[90 (1417131688592408576)]} 0 4
[junit4:junit4]   2> 6729 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[79 (-1417131688598700032)]} 0 0
[junit4:junit4]   2> 6730 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6731 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[10075 (-1417131688598700032)]} 0 2
[junit4:junit4]   2> 6733 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6734 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[79 (-1417131688601845760)]} 0 2
[junit4:junit4]   2> 6734 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10084 (1417131688603942912)]} 0 0
[junit4:junit4]   2> 6759 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[91 (1417131688607088640)]} 0 22
[junit4:junit4]   2> 6761 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 6762 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10084 (1417131688607088640)]} 0 25
[junit4:junit4]   2> 6765 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10077 (-1417131688636448768)]} 0 0
[junit4:junit4]   2> 6766 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6767 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[91 (1417131688633303040)]} 0 5
[junit4:junit4]   2> 6769 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6770 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10077 (-1417131688638545920)]} 0 3
[junit4:junit4]   2> 6770 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[92 (1417131688641691648)]} 0 0
[junit4:junit4]   2> 6773 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10085 (1417131688643788800)]} 0 1
[junit4:junit4]   2> 6775 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6776 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[92 (1417131688644837376)]} 0 3
[junit4:junit4]   2> 6777 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6778 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10085 (1417131688646934528)]} 0 3
[junit4:junit4]   2> 6779 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[84 (-1417131688651128832)]} 0 0
[junit4:junit4]   2> 6781 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10086 (1417131688653225984)]} 0 0
[junit4:junit4]   2> 6783 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6784 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[84 (-1417131688654274560)]} 0 2
[junit4:junit4]   2> 6786 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6786 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[93 (1417131688658468864)]} 0 0
[junit4:junit4]   2> 6787 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10086 (1417131688655323136)]} 0 4
[junit4:junit4]   2> 6790 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10078 (-1417131688661614592)]} 0 1
[junit4:junit4]   2> 6791 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6792 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[93 (1417131688661614592)]} 0 3
[junit4:junit4]   2> 6794 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6794 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[10078 (-1417131688664760320)]} 0 2
[junit4:junit4]   2> 6794 T1688 C68 P54839 /update {version=2&wt=javabin} {delete=[86 (-1417131688666857472)]} 0 0
[junit4:junit4]   2> 6799 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6800 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[86 (-1417131688671051776)]} 0 2
[junit4:junit4]   2> 6805 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10087 (1417131688670003200)]} 0 8
[junit4:junit4]   2> 6805 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[94 (1417131688676294656)]} 0 2
[junit4:junit4]   2> 6810 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6810 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6811 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10087 (1417131688680488960)]} 0 4
[junit4:junit4]   2> 6811 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[94 (1417131688681537536)]} 0 3
[junit4:junit4]   2> 6814 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10088 (1417131688687828992)]} 0 0
[junit4:junit4]   2> 6817 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[95 (1417131688687828993)]} 0 3
[junit4:junit4]   2> 6819 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6820 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10088 (1417131688689926144)]} 0 4
[junit4:junit4]   2> 6822 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6823 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[95 (1417131688693071872)]} 0 4
[junit4:junit4]   2> 6823 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10089 (1417131688696217600)]} 0 1
[junit4:junit4]   2> 6825 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[87 (-1417131688699363328)]} 0 0
[junit4:junit4]   2> 6829 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6829 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[87 (-1417131688701460480)]} 0 2
[junit4:junit4]   2> 6842 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[96 (1417131688705654784)]} 0 11
[junit4:junit4]   2> 6851 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 6853 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10089 (1417131688700411904)]} 0 27
[junit4:junit4]   2> 6855 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10090 (1417131688730820608)]} 0 0
[junit4:junit4]   2> 6857 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=9 
[junit4:junit4]   2> 6858 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[96 (1417131688719286272)]} 0 14
[junit4:junit4]   2> 6860 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[90 (-1417131688736063488)]} 0 0
[junit4:junit4]   2> 6860 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6862 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10090 (1417131688733966336)]} 0 4
[junit4:junit4]   2> 6864 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6864 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[10079 (-1417131688740257792)]} 0 0
[junit4:junit4]   2> 6865 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[90 (-1417131688739209216)]} 0 3
[junit4:junit4]   2> 6869 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6868 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[97 (1417131688744452096)]} 0 0
[junit4:junit4]   2> 6870 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10079 (-1417131688743403520)]} 0 3
[junit4:junit4]   2> 6873 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10091 (1417131688749694976)]} 0 0
[junit4:junit4]   2> 6874 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6875 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[97 (1417131688748646400)]} 0 3
[junit4:junit4]   2> 6878 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[93 (-1417131688754937856)]} 0 0
[junit4:junit4]   2> 6878 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6880 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10091 (1417131688752840704)]} 0 4
[junit4:junit4]   2> 6882 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6883 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10092 (1417131688759132160)]} 0 1
[junit4:junit4]   2> 6883 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[93 (-1417131688758083584)]} 0 2
[junit4:junit4]   2> 6887 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[98 (1417131688763326464)]} 0 1
[junit4:junit4]   2> 6887 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6889 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10092 (1417131688762277888)]} 0 4
[junit4:junit4]   2> 6891 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10084 (-1417131688768569344)]} 0 0
[junit4:junit4]   2> 6892 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6893 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[98 (1417131688766472192)]} 0 4
[junit4:junit4]   2> 6895 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6896 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[99 (1417131688772763648)]} 0 1
[junit4:junit4]   2> 6896 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10084 (-1417131688771715072)]} 0 3
[junit4:junit4]   2> 6899 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10093 (1417131688776957952)]} 0 0
[junit4:junit4]   2> 6900 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6902 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[99 (1417131688775909376)]} 0 4
[junit4:junit4]   2> 6904 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6904 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10093 (1417131688779055104)]} 0 3
[junit4:junit4]   2> 6905 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[100 (1417131688782200832)]} 0 1
[junit4:junit4]   2> 6907 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10086 (-1417131688785346560)]} 0 1
[junit4:junit4]   2> 6909 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6910 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[100 (1417131688785346560)]} 0 3
[junit4:junit4]   2> 6910 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6912 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[10086 (-1417131688787443712)]} 0 3
[junit4:junit4]   2> 6913 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[96 (-1417131688791638016)]} 0 0
[junit4:junit4]   2> 6915 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10094 (1417131688792686592)]} 0 1
[junit4:junit4]   2> 6916 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6917 T1704 C67 P50827 /update {version=2&wt=javabin} {delete=[96 (-1417131688793735168)]} 0 2
[junit4:junit4]   2> 6920 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[101 (1417131688797929472)]} 0 1
[junit4:junit4]   2> 6936 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6937 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[101 (1417131688812609536)]} 0 4
[junit4:junit4]   2> 6938 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=18 
[junit4:junit4]   2> 6939 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[10094 (1417131688795832320)]} 0 22
[junit4:junit4]   2> 6939 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[97 (-1417131688818900992)]} 0 0
[junit4:junit4]   2> 6943 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6944 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[97 (-1417131688822046720)]} 0 2
[junit4:junit4]   2> 6947 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[102 (1417131688826241024)]} 0 1
[junit4:junit4]   2> 6949 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[10095 (1417131688820998144)]} 0 8
[junit4:junit4]   2> 6956 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6957 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10095 (1417131688832532480)]} 0 5
[junit4:junit4]   2> 6959 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10087 (-1417131688839872512)]} 0 0
[junit4:junit4]   2> 6961 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6961 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[102 (1417131688829386752)]} 0 12
[junit4:junit4]   2> 6963 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6964 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10087 (-1417131688841969664)]} 0 3
[junit4:junit4]   2> 6966 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[103 (1417131688845115392)]} 0 2
[junit4:junit4]   2> 6967 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10096 (1417131688848261120)]} 0 0
[junit4:junit4]   2> 6970 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6971 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[103 (1417131688849309696)]} 0 3
[junit4:junit4]   2> 6972 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6973 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10096 (1417131688851406848)]} 0 3
[junit4:junit4]   2> 6974 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[104 (1417131688854552576)]} 0 1
[junit4:junit4]   2> 6975 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10090 (-1417131688856649728)]} 0 0
[junit4:junit4]   2> 6978 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6979 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6979 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[104 (1417131688857698304)]} 0 3
[junit4:junit4]   2> 6980 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10090 (-1417131688858746880)]} 0 3
[junit4:junit4]   2> 6982 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[98 (-1417131688863989760)]} 0 0
[junit4:junit4]   2> 6983 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10097 (1417131688865038336)]} 0 0
[junit4:junit4]   2> 6986 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6987 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[98 (-1417131688866086912)]} 0 3
[junit4:junit4]   2> 6988 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6989 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[10097 (1417131688868184064)]} 0 3
[junit4:junit4]   2> 6990 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[105 (1417131688871329792)]} 0 1
[junit4:junit4]   2> 6991 T1690 C68 P54839 /update {version=2&wt=javabin} {delete=[10093 (-1417131688873426944)]} 0 0
[junit4:junit4]   2> 6994 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6995 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6995 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[105 (1417131688874475520)]} 0 3
[junit4:junit4]   2> 6996 T1702 C67 P50827 /update {version=2&wt=javabin} {delete=[10093 (-1417131688875524096)]} 0 3
[junit4:junit4]   2> 6998 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[100 (-1417131688880766976)]} 0 0
[junit4:junit4]   2> 7002 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7002 T1703 C67 P50827 /update {version=2&wt=javabin} {delete=[100 (-1417131688882864128)]} 0 2
[junit4:junit4]   2> 7005 T1687 C68 P54839 /update {version=2&wt=javabin} {add=[106 (1417131688887058432)]} 0 1
[junit4:junit4]   2> 7007 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[10098 (1417131688881815552)]} 0 8
[junit4:junit4]   2> 7010 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7011 T1701 C67 P50827 /update {version=2&wt=javabin} {add=[106 (1417131688891252736)]} 0 3
[junit4:junit4]   2> 7025 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[107 (1417131688909078528)]} 0 1
[junit4:junit4]   2> 7031 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=20 
[junit4:junit4]   2> 7031 T1713 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7032 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10098 (1417131688892301312)]} 0 23
[junit4:junit4]   2> 7033 T1702 C67 P50827 /update {version=2&wt=javabin} {add=[107 (1417131688912224256)]} 0 5
[junit4:junit4]   2> 7036 T1689 C68 P54839 /update {version=2&wt=javabin} {add=[10099 (1417131688919564288)]} 0 1
[junit4:junit4]   2> 7036 T1687 C68 P54839 /update {version=2&wt=javabin} {delete=[102 (-1417131688920612864)]} 0 0
[junit4:junit4]   2> 7040 T1715 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7040 T1714 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7042 T1701 C67 P50827 /update {version=2&wt=javabin} {delete=[102 (-1417131688923758592)]} 0 3
[junit4:junit4]   2> 7042 T1703 C67 P50827 /update {version=2&wt=javabin} {add=[10099 (1417131688922710016)]} 0 4
[junit4:junit4]   2> 7046 T1690 C68 P54839 /update {version=2&wt=javabin} {add=[10100 (1417131688931098624)]} 0 0
[junit4:junit4]   2> 7046 T1688 C68 P54839 /update {version=2&wt=javabin} {add=[108 (1417131688930050048)]} 0 1
[junit4:junit4]   2> 7051 T1716 C66 P40940 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:50827/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7052 T1704 C67 P50827 /update {version=2&wt=javabin} {add=[10100 (1417131688934244352)]} 0 3
[junit4:junit4]   2> 7054 T1689 C68 P54839 /update {version=2&wt=javabin} {delete=[10096 (-1417131688939487232)]} 0 0
[junit4:junit4]   2> 7058 T1713 C66 P40

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

> 27303 T1706 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 27303 T1672 oaz.ZooKeeper.close Session: 0x13aaa9c18ed0003 closed
[junit4:junit4]   2> 27325 T1672 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 27377 T1672 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 40940
[junit4:junit4]   2> 27378 T1672 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2140962716
[junit4:junit4]   2> 28965 T1807 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34231
[junit4:junit4]   2> 29066 T1808 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 29066 T1672 oaz.ZooKeeper.close Session: 0x13aaa9c18ed0007 closed
[junit4:junit4]   2> 29088 T1672 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 29197 T1672 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=79897B6D3F80E80A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=el_CY_EURO -Dtests.timezone=Pacific/Rarotonga -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   29.2s J0 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:40940/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([79897B6D3F80E80A:F86FF57548DF8836]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:611)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:738)
[junit4:junit4]   2> 29223 T1672 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Compressing(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_UNCOMPRESSION, chunkSize=43, storedFieldsIndex=MEMORY_DOC)), sim=DefaultSimilarity, locale=el_CY_EURO, timezone=Pacific/Rarotonga
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic x86/IBM Corporation 1.6.0 (32-bit)/cpus=8,threads=1,free=7703976,total=77214208
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestSolrQueryParser, TestCollationField, NoCacheHeaderTest, TestStressReorder, CurrencyFieldTest, TestLFUCache, TestIndexingPerformance, TestFastOutputStream, FastVectorHighlighterTest, TestElisionMultitermQuery, TestConfig, DistributedTermsComponentTest, UpdateRequestProcessorFactoryTest, TestRangeQuery, TermsComponentTest, TestNumberUtils, RequestHandlersTest, TestJoin, TestSolrIndexConfig, DateMathParserTest, TestSearchPerf, CoreAdminHandlerTest, FieldAnalysisRequestHandlerTest, SpellCheckCollatorTest, PluginInfoTest, SampleTest, TermVectorComponentDistributedTest, JSONWriterTest, NumericFieldsTest, LoggingHandlerTest, QueryElevationComponentTest, TestBinaryResponseWriter, MinimalSchemaTest, TestBadConfig, TestFaceting, SpatialFilterTest, CircularListTest, TestFastLRUCache, CSVRequestHandlerTest, SyncSliceTest, TestLMJelinekMercerSimilarityFactory, SystemInfoHandlerTest, SpellCheckComponentTest, TestDefaultSimilarityFactory, LeaderElectionIntegrationTest, TestJmxMonitoredMap, AutoCommitTest, TestIndexSearcher, TestQuerySenderNoQuery, SolrCmdDistributorTest, MoreLikeThisHandlerTest, SuggesterWFSTTest, BadComponentTest, StatelessScriptUpdateProcessorFactoryTest, DirectSolrConnectionTest, OverseerTest, RecoveryZkTest]
[junit4:junit4] Completed on J0 in 29.25s, 1 test, 1 error <<< FAILURES!

[...truncated 357 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:348: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1159: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:827: There were test failures: 233 suites, 965 tests, 1 error, 7 ignored (2 assumptions)

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



Mime
View raw message