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/jdk1.8.0-ea-b51) - Build # 1062 - Still Failing!
Date Thu, 13 Sep 2012 03:27:40 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/1062/
Java: 32bit/jdk1.8.0-ea-b51 -server -XX:+UseG1GC

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

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

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




Build Log:
[...truncated 7497 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 4 T409 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1347506405712
[junit4:junit4]   2> 5 T409 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 6 T410 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 7 T410 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 7 T410 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 106 T409 oasc.ZkTestServer.run start zk server on port:39557
[junit4:junit4]   2> 106 T409 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:39557 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1258794
[junit4:junit4]   2> 107 T415 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:39557
[junit4:junit4]   2> 107 T409 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 107 T415 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:39557, initiating session
[junit4:junit4]   2> 107 T411 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:49527
[junit4:junit4]   2> 108 T411 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:49527
[junit4:junit4]   2> 108 T413 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 418 T413 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bda401530000 with negotiated timeout 10000 for client /127.0.0.1:49527
[junit4:junit4]   2> 419 T415 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:39557, sessionid = 0x139bda401530000, negotiated timeout = 10000
[junit4:junit4]   2> 419 T416 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1258794 name:ZooKeeperConnection Watcher:127.0.0.1:39557 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 419 T409 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 419 T409 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 424 T414 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139bda401530000
[junit4:junit4]   2> 425 T416 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 425 T411 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139bda401530000, likely client has closed socket
[junit4:junit4]   2> 425 T409 oaz.ZooKeeper.close Session: 0x139bda401530000 closed
[junit4:junit4]   2> 426 T409 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:39557/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@13cff1c
[junit4:junit4]   2> 426 T411 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:49527 which had sessionid 0x139bda401530000
[junit4:junit4]   2> 427 T417 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:39557
[junit4:junit4]   2> 427 T409 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 427 T417 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:39557, initiating session
[junit4:junit4]   2> 427 T411 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:49528
[junit4:junit4]   2> 428 T411 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:49528
[junit4:junit4]   2> 428 T413 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bda401530001 with negotiated timeout 10000 for client /127.0.0.1:49528
[junit4:junit4]   2> 428 T417 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:39557, sessionid = 0x139bda401530001, negotiated timeout = 10000
[junit4:junit4]   2> 429 T418 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13cff1c name:ZooKeeperConnection Watcher:127.0.0.1:39557/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 429 T409 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 430 T409 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 432 T409 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 436 T409 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 438 T409 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 441 T409 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> 455 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 477 T409 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> 480 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 484 T409 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> 486 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 494 T409 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> 495 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 498 T409 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> 498 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 501 T409 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> 502 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 504 T409 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> 505 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 508 T409 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> 509 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 512 T409 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> 513 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 515 T409 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> 516 T409 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 517 T414 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139bda401530001
[junit4:junit4]   2> 518 T411 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:49528 which had sessionid 0x139bda401530001
[junit4:junit4]   2> 518 T418 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 519 T409 oaz.ZooKeeper.close Session: 0x139bda401530001 closed
[junit4:junit4]   2> 520 T409 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 531 T409 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:46100
[junit4:junit4]   2> 533 T409 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 533 T409 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> 534 T409 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> 551 T409 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 552 T409 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 552 T409 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> 553 T409 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> 553 T409 oasc.CoreContainer.<init> New CoreContainer 28543424
[junit4:junit4]   2> 554 T409 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> 554 T409 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> 572 T409 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 584 T409 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:39557/solr
[junit4:junit4]   2> 594 T409 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:39557/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@adb618
[junit4:junit4]   2> 595 T428 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:39557
[junit4:junit4]   2> 595 T409 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 596 T428 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:39557, initiating session
[junit4:junit4]   2> 596 T411 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:49529
[junit4:junit4]   2> 596 T411 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:49529
[junit4:junit4]   2> 597 T413 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bda401530002 with negotiated timeout 8000 for client /127.0.0.1:49529
[junit4:junit4]   2> 597 T428 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:39557, sessionid = 0x139bda401530002, negotiated timeout = 8000
[junit4:junit4]   2> 597 T429 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@adb618 name:ZooKeeperConnection Watcher:127.0.0.1:39557/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 597 T409 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 599 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 600 T409 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 601 T409 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46100_solr
[junit4:junit4]   2> 602 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:46100_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:46100_solr
[junit4:junit4]   2> 602 T409 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46100_solr
[junit4:junit4]   2> 605 T409 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 606 T409 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 606 T409 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 607 T409 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 607 T409 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 608 T409 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 608 T409 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 609 T409 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 609 T409 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 610 T409 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 615 T409 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 668 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 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> 668 T409 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 671 T409 oasc.Overseer.start Overseer (id=88310179804938242-127.0.0.1:46100_solr-n_0000000000) starting
[junit4:junit4]   2> 672 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 675 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 676 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 678 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 679 T431 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 680 T409 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 683 T409 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 689 T430 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 693 T409 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> 693 T409 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 694 T409 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 694 T409 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 695 T409 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> 696 T409 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> 697 T409 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> 730 T409 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 817 T409 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 818 T409 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 824 T409 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1263 T409 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1269 T409 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1272 T409 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1280 T409 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1283 T409 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1286 T409 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1288 T409 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1289 T409 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-1347506405712/control/data/
[junit4:junit4]   2> 1290 T409 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36e2bf
[junit4:junit4]   2> 1300 T409 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347506405712/control/data/index/
[junit4:junit4]   2> 1301 T409 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347506405712/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1302 T409 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347506405712/control/data/index forceNew:false
[junit4:junit4]   2> 1438 T409 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347506405712/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@ed20d2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1438 T409 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1439 T409 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1440 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1441 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1441 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1441 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1442 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1442 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1443 T409 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1443 T409 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1443 T409 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1444 T409 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1444 T409 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1445 T409 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1445 T409 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1445 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1446 T409 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1446 T409 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1447 T409 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1447 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1448 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1448 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1448 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1449 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1449 T409 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1450 T409 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1450 T409 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1451 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1453 T409 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1454 T409 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [STOPWORDS.TXT, PROTWORDS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, SYNONYMS.TXT, CURRENCY.XML, OLD_SYNONYMS.TXT, OPEN-EXCHANGE-RATES.JSON, SCHEMA.XML]
[junit4:junit4]   2> 1457 T409 oass.SolrIndexSearcher.<init> Opening Searcher@1871523 main
[junit4:junit4]   2> 1458 T409 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1463 T409 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1464 T409 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1477 T409 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1522 T432 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1871523 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1527 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 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> 1528 T409 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1529 T409 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:46100/solr shardId:control_shard
[junit4:junit4]   2> 1533 T409 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 1538 T409 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1542 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 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> 1543 T409 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 1544 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1547 T409 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:46100/solr/collection1/
[junit4:junit4]   2> 1548 T409 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1550 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:create cxid:0x6c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1695 T430 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1699 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530002 type:create cxid:0x74 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1805 T429 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1852 T409 oasc.ZkController.register We are http://127.0.0.1:46100/solr/collection1/ and leader is http://127.0.0.1:46100/solr/collection1/
[junit4:junit4]   2> 1852 T409 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:46100/solr
[junit4:junit4]   2> 1853 T409 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1855 T409 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1855 T409 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1856 T409 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1856 T409 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1859 T409 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1861 T409 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:52643
[junit4:junit4]   2> 1861 T409 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1862 T409 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> 1862 T409 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> 1890 T409 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1891 T409 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1891 T409 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> 1892 T409 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> 1892 T409 oasc.CoreContainer.<init> New CoreContainer 1791030
[junit4:junit4]   2> 1893 T409 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> 1893 T409 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> 1911 T409 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1923 T409 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:39557/solr
[junit4:junit4]   2> 1924 T409 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:39557/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1260d76
[junit4:junit4]   2> 1924 T442 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:39557
[junit4:junit4]   2> 1924 T409 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1925 T411 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:49531
[junit4:junit4]   2> 1926 T442 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:39557, initiating session
[junit4:junit4]   2> 1926 T411 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:49531
[junit4:junit4]   2> 1927 T413 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bda401530003 with negotiated timeout 8000 for client /127.0.0.1:49531
[junit4:junit4]   2> 1927 T442 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:39557, sessionid = 0x139bda401530003, negotiated timeout = 8000
[junit4:junit4]   2> 1927 T443 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1260d76 name:ZooKeeperConnection Watcher:127.0.0.1:39557/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1928 T409 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1929 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1931 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1935 T409 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52643_solr
[junit4:junit4]   2> 1936 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:52643_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:52643_solr
[junit4:junit4]   2> 1936 T409 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52643_solr
[junit4:junit4]   2> 1938 T429 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 1939 T409 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1939 T409 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1940 T409 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1940 T409 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1941 T409 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1941 T409 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1942 T409 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1942 T409 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1943 T409 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1943 T409 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1949 T409 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1954 T409 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> 1955 T409 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 1956 T409 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1956 T409 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 1957 T409 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> 1958 T409 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> 1959 T409 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> 1990 T409 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 2062 T409 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2063 T409 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2067 T409 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2310 T430 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2313 T443 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2313 T429 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2480 T409 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2488 T409 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2491 T409 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2502 T409 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2506 T409 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2509 T409 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2511 T409 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2521 T409 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-1347506405712/jetty1/
[junit4:junit4]   2> 2521 T409 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36e2bf
[junit4:junit4]   2> 2523 T409 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty1/index/
[junit4:junit4]   2> 2524 T409 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2525 T409 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty1/index forceNew:false
[junit4:junit4]   2> 2608 T409 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1857d5e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2609 T409 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2609 T409 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2611 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2611 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2611 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2612 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2612 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2613 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2613 T409 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2614 T409 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2614 T409 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2615 T409 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2615 T409 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2616 T409 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2616 T409 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2617 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2617 T409 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2617 T409 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2618 T409 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2618 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2619 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2619 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2620 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2620 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2621 T409 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2622 T409 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2622 T409 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2624 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2626 T409 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2627 T409 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [STOPWORDS.TXT, PROTWORDS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, SYNONYMS.TXT, CURRENCY.XML, OLD_SYNONYMS.TXT, OPEN-EXCHANGE-RATES.JSON, SCHEMA.XML]
[junit4:junit4]   2> 2630 T409 oass.SolrIndexSearcher.<init> Opening Searcher@1fd899f main
[junit4:junit4]   2> 2631 T409 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2632 T409 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2633 T409 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2636 T409 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2657 T444 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fd899f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2816 T430 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2820 T429 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2820 T443 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3161 T409 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3161 T409 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:52643/solr shardId:shard1
[junit4:junit4]   2> 3162 T409 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3287 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530003 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> 3287 T409 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 3288 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3289 T409 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52643/solr/collection1/
[junit4:junit4]   2> 3289 T409 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3292 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3323 T430 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3328 T443 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3328 T429 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3344 T409 oasc.ZkController.register We are http://127.0.0.1:52643/solr/collection1/ and leader is http://127.0.0.1:52643/solr/collection1/
[junit4:junit4]   2> 3345 T409 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52643/solr
[junit4:junit4]   2> 3345 T409 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3347 T409 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3348 T409 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3348 T409 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3349 T409 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3355 T409 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3357 T409 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:34541
[junit4:junit4]   2> 3358 T409 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3358 T409 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> 3359 T409 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> 3376 T409 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3377 T409 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3377 T409 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> 3378 T409 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> 3378 T409 oasc.CoreContainer.<init> New CoreContainer 20159150
[junit4:junit4]   2> 3379 T409 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> 3379 T409 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> 3393 T409 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3401 T409 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:39557/solr
[junit4:junit4]   2> 3401 T409 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:39557/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c893e5
[junit4:junit4]   2> 3402 T454 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:39557
[junit4:junit4]   2> 3402 T409 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3403 T454 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:39557, initiating session
[junit4:junit4]   2> 3403 T411 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:49533
[junit4:junit4]   2> 3403 T411 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:49533
[junit4:junit4]   2> 3404 T413 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bda401530004 with negotiated timeout 8000 for client /127.0.0.1:49533
[junit4:junit4]   2> 3404 T454 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:39557, sessionid = 0x139bda401530004, negotiated timeout = 8000
[junit4:junit4]   2> 3407 T455 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c893e5 name:ZooKeeperConnection Watcher:127.0.0.1:39557/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3407 T409 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3408 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3408 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3409 T409 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34541_solr
[junit4:junit4]   2> 3409 T414 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bda401530004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34541_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34541_solr
[junit4:junit4]   2> 3410 T409 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34541_solr
[junit4:junit4]   2> 3411 T429 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3411 T443 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3412 T409 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3412 T409 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3412 T409 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3413 T409 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3413 T409 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3413 T409 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3413 T409 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3414 T409 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3414 T409 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3414 T409 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3418 T409 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3422 T409 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> 3423 T409 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3423 T409 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3424 T409 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3425 T409 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> 3425 T409 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> 3426 T409 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> 3452 T409 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3495 T409 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3496 T409 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3502 T409 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3831 T430 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3891 T409 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3898 T409 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3901 T409 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3919 T455 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3919 T429 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3920 T443 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3930 T409 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3935 T409 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3938 T409 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3940 T409 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3941 T409 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-1347506405712/jetty2/
[junit4:junit4]   2> 3941 T409 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36e2bf
[junit4:junit4]   2> 3942 T409 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty2/index/
[junit4:junit4]   2> 3943 T409 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3944 T409 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty2/index forceNew:false
[junit4:junit4]   2> 3948 T409 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f796ee; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3948 T409 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3949 T409 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3951 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3951 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3952 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3952 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3952 T409 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3953 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3953 T409 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3954 T409 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3954 T409 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3955 T409 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3955 T409 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3956 T409 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3956 T409 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3957 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3957 T409 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3958 T409 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3958 T409 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3958 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3959 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3960 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3960 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3961 T409 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3961 T409 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3962 T409 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3962 T409 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3963 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3965 T409 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3966 T409 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [STOPWORDS.TXT, PROTWORDS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, SYNONYMS.TXT, CURRENCY.XML, OLD_SYNONYMS.TXT, OPEN-EXCHANGE-RATES.JSON, SCHEMA.XML]
[junit4:junit4]   2> 3968 T409 oass.SolrIndexSearcher.<init> Opening Searcher@7155cd main
[junit4:junit4]   2> 3969 T409 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3970 T409 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3970 T409 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3974 T409 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4005 T456 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7155cd main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4423 T430 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4426 T455 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4426 T443 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4426 T429 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4511 T409 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4512 T409 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:34541/solr shardId:shard1
[junit4:junit4]   2> 4514 T409 oasc.ZkController.register We are http://127.0.0.1:34541/solr/collection1/ and leader is http://127.0.0.1:52643/solr/collection1/
[junit4:junit4]   2> 4515 T409 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34541/solr
[junit4:junit4]   2> 4516 T409 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 4516 T409 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C16 name=collection1 org.apache.solr.core.SolrCore@f0e803 url=http://127.0.0.1:34541/solr/collection1 node=127.0.0.1:34541_solr
[junit4:junit4]   2> 4521 T457 C16 P34541 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 4521 T409 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4521 T457 C16 P34541 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 4522 T409 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4522 T457 C16 P34541 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4522 T409 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4523 T409 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4527 T409 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:39557/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@e19728
[junit4:junit4]   2> 4528 T458 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:39557
[junit4:junit4]   2> 4528 T409 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4531 T458 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:39557, initiating session
[junit4:junit4]   2> 4531 T411 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:49535
[junit4:junit4]   2> 4531 T411 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:49535
[junit4:junit4]   2> 4532 T413 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bda401530005 with negotiated timeout 10000 for client /127.0.0.1:49535
[junit4:junit4]   2> 4532 T458 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:39557, sessionid = 0x139bda401530005, negotiated timeout = 10000
[junit4:junit4]   2> 4532 T459 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e19728 name:ZooKeeperConnection Watcher:127.0.0.1:39557/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4533 T409 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4533 T409 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4535 T409 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4:junit4]   2> 4535 T409 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 4539 T409 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C17 name=collection1 org.apache.solr.core.SolrCore@995bd3 url=http://127.0.0.1:46100/solr/collection1 node=127.0.0.1:46100_solr
[junit4:junit4]   2> 4550 T421 C17 P46100 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347506405712/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@ed20d2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4550 T421 C17 P46100 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4553 T422 C17 P46100 /update {wt=javabin&version=2} {add=[1 (1412962881639546881)]} 0 5
[junit4:junit4]   2> 4553 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10001 (1412962881639546880)]} 0 6
[junit4:junit4]   2> 4553 T460 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:39557/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@d8edf8
[junit4:junit4]   2> 4554 T462 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:39557
[junit4:junit4]   2> 4554 T460 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4554 T462 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:39557, initiating session
[junit4:junit4]   2> 4554 T411 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:49539
[junit4:junit4]   2> 4555 T411 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:49539
[junit4:junit4]   2> 4556 T462 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:39557, sessionid = 0x139bda401530006, negotiated timeout = 10000
[junit4:junit4]   2> 4556 T413 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bda401530006 with negotiated timeout 10000 for client /127.0.0.1:49539
[junit4:junit4]   2> 4556 T463 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d8edf8 name:ZooKeeperConnection Watcher:127.0.0.1:39557/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4556 T460 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4556 T460 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C18 name=collection1 org.apache.solr.core.SolrCore@1685e12 url=http://127.0.0.1:52643/solr/collection1 node=127.0.0.1:52643_solr
[junit4:junit4]   2> 4565 T437 C18 P52643 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1857d5e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4566 T437 C18 P52643 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4568 T436 C18 P52643 /update {wt=javabin&version=2} {add=[1 (1412962881654226945)]} 0 6
[junit4:junit4]   2> 4568 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10001 (1412962881654226944)]} 0 6
[junit4:junit4]   2> 4570 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10002 (1412962881662615552)]} 0 0
[junit4:junit4]   2> 4570 T422 C17 P46100 /update {wt=javabin&version=2} {add=[2 (1412962881662615553)]} 0 0
[junit4:junit4]   2> 4573 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10002 (1412962881664712704)]} 0 1
[junit4:junit4]   2> 4573 T436 C18 P52643 /update {wt=javabin&version=2} {add=[2 (1412962881664712705)]} 0 1
[junit4:junit4]   2> 4575 T421 C17 P46100 /update {wt=javabin&version=2} {add=[3 (1412962881667858432)]} 0 0
[junit4:junit4]   2> 4576 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10003 (1412962881668907008)]} 0 1
[junit4:junit4]   2> 4578 T436 C18 P52643 /update {wt=javabin&version=2} {add=[3 (1412962881669955584)]} 0 1
[junit4:junit4]   2> 4578 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10003 (1412962881671004160)]} 0 1
[junit4:junit4]   2> 4581 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[3 (-1412962881674149888)]} 0 1
[junit4:junit4]   2> 4581 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10003 (-1412962881674149889)]} 0 1
[junit4:junit4]   2> 4584 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[3 (-1412962881677295616)]} 0 1
[junit4:junit4]   2> 4584 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10003 (-1412962881677295617)]} 0 1
[junit4:junit4]   2> 4587 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10004 (1412962881680441344)]} 0 1
[junit4:junit4]   2> 4587 T422 C17 P46100 /update {wt=javabin&version=2} {add=[4 (1412962881680441345)]} 0 1
[junit4:junit4]   2> 4590 T437 C18 P52643 /update {wt=javabin&version=2} {add=[4 (1412962881683587072)]} 0 1
[junit4:junit4]   2> 4591 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10004 (1412962881683587073)]} 0 2
[junit4:junit4]   2> 4593 T422 C17 P46100 /update {wt=javabin&version=2} {add=[5 (1412962881685684224)]} 0 1
[junit4:junit4]   2> 4597 T436 C18 P52643 /update {wt=javabin&version=2} {add=[5 (1412962881688829952)]} 0 3
[junit4:junit4]   2> 4597 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10005 (1412962881685684225)]} 0 5
[junit4:junit4]   2> 4600 T422 C17 P46100 /update {wt=javabin&version=2} {add=[6 (1412962881693024256)]} 0 1
[junit4:junit4]   2> 4600 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10005 (1412962881693024256)]} 0 1
[junit4:junit4]   2> 4602 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10006 (1412962881696169984)]} 0 0
[junit4:junit4]   2> 4602 T437 C18 P52643 /update {wt=javabin&version=2} {add=[6 (1412962881696169984)]} 0 0
[junit4:junit4]   2> 4612 T422 C17 P46100 /update {wt=javabin&version=2} {add=[7 (1412962881699315712)]} 0 8
[junit4:junit4]   2> 4612 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10006 (1412962881698267136)]} 0 8
[junit4:junit4]   2> 4615 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10007 (1412962881709801472)]} 0 1
[junit4:junit4]   2> 4615 T436 C18 P52643 /update {wt=javabin&version=2} {add=[7 (1412962881709801472)]} 0 1
[junit4:junit4]   2> 4628 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[4 (-1412962881711898624)]} 0 11
[junit4:junit4]   2> 4628 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10007 (1412962881711898624)]} 0 11
[junit4:junit4]   2> 4631 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10004 (-1412962881725530112)]} 0 1
[junit4:junit4]   2> 4631 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[4 (-1412962881726578688)]} 0 1
[junit4:junit4]   2> 4633 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10004 (-1412962881728675840)]} 0 1
[junit4:junit4]   2> 4635 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10008 (1412962881729724416)]} 0 1
[junit4:junit4]   2> 4636 T422 C17 P46100 /update {wt=javabin&version=2} {add=[8 (1412962881728675840)]} 0 4
[junit4:junit4]   2> 4638 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10008 (1412962881732870144)]} 0 1
[junit4:junit4]   2> 4639 T436 C18 P52643 /update {wt=javabin&version=2} {add=[8 (1412962881733918720)]} 0 1
[junit4:junit4]   2> 4640 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10009 (1412962881734967296)]} 0 1
[junit4:junit4]   2> 4641 T421 C17 P46100 /update {wt=javabin&version=2} {add=[9 (1412962881737064448)]} 0 0
[junit4:junit4]   2> 4644 T437 C18 P52643 /update {wt=javabin&version=2} {add=[9 (1412962881739161600)]} 0 1
[junit4:junit4]   2> 4646 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10009 (1412962881738113024)]} 0 4
[junit4:junit4]   2> 4646 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10 (1412962881742307328)]} 0 0
[junit4:junit4]   2> 4649 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10010 (1412962881745453056)]} 0 0
[junit4:junit4]   2> 4652 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10010 (1412962881748598784)]} 0 0
[junit4:junit4]   2> 4656 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10011 (1412962881751744512)]} 0 0
[junit4:junit4]   2> 4664 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10011 (1412962881754890240)]} 0 6
[junit4:junit4]   2> 4665 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10 (1412962881745453056)]} 0 16
[junit4:junit4]   2> 4666 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10006 (-1412962881763278848)]} 0 0
[junit4:junit4]   2> 4668 T421 C17 P46100 /update {wt=javabin&version=2} {add=[11 (1412962881764327424)]} 0 1
[junit4:junit4]   2> 4669 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10006 (-1412962881766424576)]} 0 1
[junit4:junit4]   2> 4672 T437 C18 P52643 /update {wt=javabin&version=2} {add=[11 (1412962881767473152)]} 0 2
[junit4:junit4]   2> 4673 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10012 (1412962881767473152)]} 0 3
[junit4:junit4]   2> 4675 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[6 (-1412962881772716032)]} 0 1
[junit4:junit4]   2> 4675 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10012 (1412962881772716032)]} 0 0
[junit4:junit4]   2> 4677 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[6 (-1412962881774813184)]} 0 1
[junit4:junit4]   2> 4677 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10007 (-1412962881774813184)]} 0 0
[junit4:junit4]   2> 4679 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10007 (-1412962881776910336)]} 0 0
[junit4:junit4]   2> 4679 T421 C17 P46100 /update {wt=javabin&version=2} {add=[12 (1412962881775861760)]} 0 1
[junit4:junit4]   2> 4681 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10013 (1412962881779007488)]} 0 0
[junit4:junit4]   2> 4681 T436 C18 P52643 /update {wt=javabin&version=2} {add=[12 (1412962881779007488)]} 0 0
[junit4:junit4]   2> 4683 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[7 (-1412962881781104640)]} 0 0
[junit4:junit4]   2> 4685 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10013 (1412962881783201792)]} 0 2
[junit4:junit4]   2> 4686 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[7 (-1412962881784250368)]} 0 1
[junit4:junit4]   2> 4687 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10014 (1412962881785298944)]} 0 0
[junit4:junit4]   2> 4688 T421 C17 P46100 /update {wt=javabin&version=2} {add=[13 (1412962881786347520)]} 0 0
[junit4:junit4]   2> 4689 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10014 (1412962881787396096)]} 0 0
[junit4:junit4]   2> 4690 T437 C18 P52643 /update {wt=javabin&version=2} {add=[13 (1412962881788444672)]} 0 0
[junit4:junit4]   2> 4691 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10009 (-1412962881789493248)]} 0 0
[junit4:junit4]   2> 4693 T422 C17 P46100 /update {wt=javabin&version=2} {add=[14 (1412962881790541824)]} 0 1
[junit4:junit4]   2> 4693 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10009 (-1412962881791590400)]} 0 0
[junit4:junit4]   2> 4696 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10015 (1412962881793687552)]} 0 1
[junit4:junit4]   2> 4696 T436 C18 P52643 /update {wt=javabin&version=2} {add=[14 (1412962881793687552)]} 0 1
[junit4:junit4]   2> 4698 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[9 (-1412962881796833280)]} 0 0
[junit4:junit4]   2> 4698 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10015 (1412962881796833280)]} 0 1
[junit4:junit4]   2> 4700 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[9 (-1412962881798930432)]} 0 0
[junit4:junit4]   2> 4702 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10016 (1412962881799979008)]} 0 1
[junit4:junit4]   2> 4702 T421 C17 P46100 /update {wt=javabin&version=2} {add=[15 (1412962881801027584)]} 0 0
[junit4:junit4]   2> 4704 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10016 (1412962881803124736)]} 0 0
[junit4:junit4]   2> 4708 T437 C18 P52643 /update {wt=javabin&version=2} {add=[15 (1412962881806270464)]} 0 1
[junit4:junit4]   2> 4708 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10017 (1412962881807319040)]} 0 0
[junit4:junit4]   2> 4710 T422 C17 P46100 /update {wt=javabin&version=2} {add=[16 (1412962881809416192)]} 0 0
[junit4:junit4]   2> 4748 T437 C18 P52643 /update {wt=javabin&version=2} {add=[16 (1412962881824096257)]} 0 24
[junit4:junit4]   2> 4748 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10017 (1412962881824096256)]} 0 24
[junit4:junit4]   2> 4750 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10010 (-1412962881851359233)]} 0 0
[junit4:junit4]   2> 4752 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10010 (-1412962881853456384)]} 0 0
[junit4:junit4]   2> 4753 T422 C17 P46100 /update {wt=javabin&version=2} {add=[17 (1412962881851359232)]} 0 3
[junit4:junit4]   2> 4755 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10018 (1412962881855553536)]} 0 1
[junit4:junit4]   2> 4755 T436 C18 P52643 /update {wt=javabin&version=2} {add=[17 (1412962881856602112)]} 0 0
[junit4:junit4]   2> 4757 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10018 (1412962881858699264)]} 0 1
[junit4:junit4]   2> 4757 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10 (-1412962881858699264)]} 0 0
[junit4:junit4]   2> 4759 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10 (-1412962881860796416)]} 0 0
[junit4:junit4]   2> 4760 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10019 (1412962881860796416)]} 0 2
[junit4:junit4]   2> 4761 T421 C17 P46100 /update {wt=javabin&version=2} {add=[18 (1412962881862893568)]} 0 1
[junit4:junit4]   2> 4762 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10019 (1412962881862893568)]} 0 1
[junit4:junit4]   2> 4763 T436 C18 P52643 /update {wt=javabin&version=2} {add=[18 (1412962881864990720)]} 0 1
[junit4:junit4]   2> 4764 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10014 (-1412962881866039296)]} 0 0
[junit4:junit4]   2> 4766 T422 C17 P46100 /update {wt=javabin&version=2} {add=[19 (1412962881867087872)]} 0 0
[junit4:junit4]   2> 4767 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10014 (-1412962881868136448)]} 0 1
[junit4:junit4]   2> 4772 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10020 (1412962881873379328)]} 0 3
[junit4:junit4]   2> 4774 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10020 (1412962881876525056)]} 0 0
[junit4:junit4]   2> 4775 T437 C18 P52643 /update {wt=javabin&version=2} {add=[19 (1412962881873379328)]} 0 8
[junit4:junit4]   2> 4777 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10018 (-1412962881879670784)]} 0 1
[junit4:junit4]   2> 4778 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[14 (-1412962881880719360)]} 0 1
[junit4:junit4]   2> 4779 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10018 (-1412962881881767936)]} 0 0
[junit4:junit4]   2> 4780 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[14 (-1412962881882816512)]} 0 0
[junit4:junit4]   2> 4782 T422 C17 P46100 /update {wt=javabin&version=2} {add=[20 (1412962881884913664)]} 0 0
[junit4:junit4]   2> 4782 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10021 (1412962881884913665)]} 0 0
[junit4:junit4]   2> 4786 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10021 (1412962881888059393)]} 0 2
[junit4:junit4]   2> 4789 T436 C18 P52643 /update {wt=javabin&version=2} {add=[20 (1412962881888059392)]} 0 5
[junit4:junit4]   2> 4789 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10022 (1412962881892253696)]} 0 1
[junit4:junit4]   2> 4791 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[18 (-1412962881894350848)]} 0 0
[junit4:junit4]   2> 4792 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10022 (1412962881894350848)]} 0 1
[junit4:junit4]   2> 4793 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[18 (-1412962881896448000)]} 0 0
[junit4:junit4]   2> 4794 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10023 (1412962881897496576)]} 0 0
[junit4:junit4]   2> 4795 T421 C17 P46100 /update {wt=javabin&version=2} {add=[21 (1412962881898545152)]} 0 0
[junit4:junit4]   2> 4796 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10023 (1412962881899593728)]} 0 0
[junit4:junit4]   2> 4797 T436 C18 P52643 /update {wt=javabin&version=2} {add=[21 (1412962881900642304)]} 0 0
[junit4:junit4]   2> 4799 T422 C17 P46100 /update {wt=javabin&version=2} {add=[22 (1412962881901690881)]} 0 1
[junit4:junit4]   2> 4801 T436 C18 P52643 /update {wt=javabin&version=2} {add=[22 (1412962881903788032)]} 0 1
[junit4:junit4]   2> 4802 T422 C17 P46100 /update {wt=javabin&version=2} {add=[23 (1412962881905885184)]} 0 0
[junit4:junit4]   2> 4802 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10024 (1412962881901690880)]} 0 4
[junit4:junit4]   2> 4804 T436 C18 P52643 /update {wt=javabin&version=2} {add=[23 (1412962881907982336)]} 0 0
[junit4:junit4]   2> 4808 T421 C17 P46100 /update {wt=javabin&version=2} {add=[24 (1412962881910079488)]} 0 2
[junit4:junit4]   2> 4808 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10024 (1412962881907982337)]} 0 4
[junit4:junit4]   2> 4810 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10020 (-1412962881914273792)]} 0 0
[junit4:junit4]   2> 4811 T436 C18 P52643 /update {wt=javabin&version=2} {add=[24 (1412962881914273792)]} 0 1
[junit4:junit4]   2> 4812 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10020 (-1412962881916370944)]} 0 0
[junit4:junit4]   2> 4812 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[20 (-1412962881916370944)]} 0 0
[junit4:junit4]   2> 4814 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[20 (-1412962881918468096)]} 0 0
[junit4:junit4]   2> 4815 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10025 (1412962881918468096)]} 0 1
[junit4:junit4]   2> 4817 T421 C17 P46100 /update {wt=javabin&version=2} {add=[25 (1412962881921613824)]} 0 1
[junit4:junit4]   2> 4817 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10025 (1412962881921613824)]} 0 1
[junit4:junit4]   2> 4819 T436 C18 P52643 /update {wt=javabin&version=2} {add=[25 (1412962881923710976)]} 0 0
[junit4:junit4]   2> 4820 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10026 (1412962881923710976)]} 0 1
[junit4:junit4]   2> 4822 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10026 (1412962881925808128)]} 0 1
[junit4:junit4]   2> 4823 T422 C17 P46100 /update {wt=javabin&version=2} {add=[26 (1412962881925808128)]} 0 2
[junit4:junit4]   2> 4826 T436 C18 P52643 /update {wt=javabin&version=2} {add=[26 (1412962881931051008)]} 0 1
[junit4:junit4]   2> 4826 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10027 (1412962881931051008)]} 0 0
[junit4:junit4]   2> 4829 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10027 (1412962881933148160)]} 0 1
[junit4:junit4]   2> 4831 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10027 (-1412962881936293888)]} 0 0
[junit4:junit4]   2> 4834 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10027 (-1412962881938391040)]} 0 1
[junit4:junit4]   2> 4836 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10028 (1412962881941536768)]} 0 0
[junit4:junit4]   2> 4840 T422 C17 P46100 /update {wt=javabin&version=2} {add=[27 (1412962881933148160)]} 0 12
[junit4:junit4]   2> 4841 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10028 (1412962881943633920)]} 0 3
[junit4:junit4]   2> 4842 T437 C18 P52643 /update {wt=javabin&version=2} {add=[27 (1412962881947828224)]} 0 0
[junit4:junit4]   2> 4844 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10029 (1412962881948876800)]} 0 1
[junit4:junit4]   2> 4848 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[27 (-1412962881954119680)]} 0 4
[junit4:junit4]   2> 4859 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10029 (1412962881956216832)]} 0 1
[junit4:junit4]   2> 4859 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[27 (-1412962881965654016)]} 0 8
[junit4:junit4]   2> 4863 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10030 (1412962881969848320)]} 0 2
[junit4:junit4]   2> 4863 T421 C17 P46100 /update {wt=javabin&version=2} {add=[28 (1412962881969848321)]} 0 2
[junit4:junit4]   2> 4866 T437 C18 P52643 /update {wt=javabin&version=2} {add=[28 (1412962881971945472)]} 0 1
[junit4:junit4]   2> 4866 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10030 (1412962881971945473)]} 0 1
[junit4:junit4]   2> 4868 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10031 (1412962881975091200)]} 0 0
[junit4:junit4]   2> 4868 T421 C17 P46100 /update {wt=javabin&version=2} {add=[29 (1412962881975091201)]} 0 0
[junit4:junit4]   2> 4870 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10031 (1412962881977188352)]} 0 0
[junit4:junit4]   2> 4871 T437 C18 P52643 /update {wt=javabin&version=2} {add=[29 (1412962881977188353)]} 0 1
[junit4:junit4]   2> 4872 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10028 (-1412962881979285504)]} 0 0
[junit4:junit4]   2> 4873 T422 C17 P46100 /update {wt=javabin&version=2} {add=[30 (1412962881979285505)]} 0 1
[junit4:junit4]   2> 4877 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10028 (-1412962881984528384)]} 0 3
[junit4:junit4]   2> 4877 T436 C18 P52643 /update {wt=javabin&version=2} {add=[30 (1412962881984528385)]} 0 3
[junit4:junit4]   2> 4880 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10032 (1412962881986625537)]} 0 1
[junit4:junit4]   2> 4880 T422 C17 P46100 /update {wt=javabin&version=2} {add=[31 (1412962881986625536)]} 0 1
[junit4:junit4]   2> 4882 T437 C18 P52643 /update {wt=javabin&version=2} {add=[31 (1412962881989771265)]} 0 1
[junit4:junit4]   2> 4882 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10032 (1412962881989771264)]} 0 1
[junit4:junit4]   2> 4884 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[28 (-1412962881991868417)]} 0 0
[junit4:junit4]   2> 4884 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10030 (-1412962881991868416)]} 0 0
[junit4:junit4]   2> 4886 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[28 (-1412962881993965568)]} 0 0
[junit4:junit4]   2> 4886 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10030 (-1412962881993965569)]} 0 0
[junit4:junit4]   2> 4889 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10033 (1412962881996062720)]} 0 1
[junit4:junit4]   2> 4889 T421 C17 P46100 /update {wt=javabin&version=2} {add=[32 (1412962881996062721)]} 0 1
[junit4:junit4]   2> 4892 T436 C18 P52643 /update {wt=javabin&version=2} {add=[32 (1412962882000257024)]} 0 1
[junit4:junit4]   2> 4895 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[30 (-1412962882003402752)]} 0 1
[junit4:junit4]   2> 4896 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10033 (1412962881999208448)]} 0 5
[junit4:junit4]   2> 4899 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[30 (-1412962882007597056)]} 0 2
[junit4:junit4]   2> 4902 T422 C17 P46100 /update {wt=javabin&version=2} {add=[33 (1412962882009694208)]} 0 1
[junit4:junit4]   2> 4903 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10034 (1412962882007597056)]} 0 5
[junit4:junit4]   2> 4904 T436 C18 P52643 /update {wt=javabin&version=2} {add=[33 (1412962882012839936)]} 0 0
[junit4:junit4]   2> 4907 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10034 (1412962882013888512)]} 0 2
[junit4:junit4]   2> 4907 T421 C17 P46100 /update {wt=javabin&version=2} {add=[34 (1412962882015985664)]} 0 1
[junit4:junit4]   2> 4909 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10035 (1412962882018082816)]} 0 0
[junit4:junit4]   2> 4912 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10035 (1412962882021228544)]} 0 0
[junit4:junit4]   2> 4912 T437 C18 P52643 /update {wt=javabin&version=2} {add=[34 (1412962882021228545)]} 0 0
[junit4:junit4]   2> 4915 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10036 (1412962882024374272)]} 0 0
[junit4:junit4]   2> 4916 T421 C17 P46100 /update {wt=javabin&version=2} {add=[35 (1412962882024374273)]} 0 1
[junit4:junit4]   2> 4918 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10036 (1412962882027520000)]} 0 0
[junit4:junit4]   2> 4921 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10031 (-1412962882030665728)]} 0 1
[junit4:junit4]   2> 4922 T437 C18 P52643 /update {wt=javabin&version=2} {add=[35 (1412962882031714304)]} 0 1
[junit4:junit4]   2> 4925 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10031 (-1412962882034860032)]} 0 0
[junit4:junit4]   2> 4928 T430 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4932 T421 C17 P46100 /update {wt=javabin&version=2} {add=[36 (1412962882041151488)]} 0 6
[junit4:junit4]   2> 4933 T429 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4934 T443 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4934 T455 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4933 T463 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4933 T459 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4941 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10037 (1412962882038005760)]} 0 14
[junit4:junit4]   2>  C16_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:34541/solr, collection=collection1, core=collection1, roles=null, shard=shard1, state=recovering, node_name=127.0.0.1:34541_solr}
[junit4:junit4]   2> 4983 T448 C16 P34541 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347506405712/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f796ee; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4985 T448 C16 P34541 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4987 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=7 
[junit4:junit4]   2> 4987 T436 C18 P52643 /update {wt=javabin&version=2} {add=[36 (1412962882049540096)]} 0 52
[junit4:junit4]   2> 4989 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=9 
[junit4:junit4]   2> 4989 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10037 (1412962882054782976)]} 0 46
[junit4:junit4]   2> 4990 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[31 (-1412962882103017472)]} 0 0
[junit4:junit4]   2> 4992 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10038 (1412962882104066048)]} 0 1
[junit4:junit4]   2> 4997 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4997 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10038 (1412962882107211776)]} 0 3
[junit4:junit4]   2> 5001 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10039 (1412962882112454656)]} 0 2
[junit4:junit4]   2> 5007 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5010 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5011 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[31 (-1412962882106163200)]} 0 18
[junit4:junit4]   2> 5011 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10039 (1412962882118746112)]} 0 6
[junit4:junit4]   2> 5014 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10038 (-1412962882128183297)]} 0 0
[junit4:junit4]   2> 5014 T421 C17 P46100 /update {wt=javabin&version=2} {add=[37 (1412962882128183296)]} 0 1
[junit4:junit4]   2> 5020 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5020 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5020 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10038 (-1412962882130280449)]} 0 4
[junit4:junit4]   2> 5020 T437 C18 P52643 /update {wt=javabin&version=2} {add=[37 (1412962882130280448)]} 0 4
[junit4:junit4]   2> 5023 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10040 (1412962882137620480)]} 0 1
[junit4:junit4]   2> 5024 T422 C17 P46100 /update {wt=javabin&version=2} {add=[38 (1412962882137620481)]} 0 1
[junit4:junit4]   2> 5028 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5028 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10040 (1412962882139717632)]} 0 3
[junit4:junit4]   2> 5029 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5029 T436 C18 P52643 /update {wt=javabin&version=2} {add=[38 (1412962882140766208)]} 0 3
[junit4:junit4]   2> 5031 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10041 (1412962882146009088)]} 0 1
[junit4:junit4]   2> 5033 T421 C17 P46100 /update {wt=javabin&version=2} {add=[39 (1412962882147057664)]} 0 1
[junit4:junit4]   2> 5037 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5037 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5037 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10041 (1412962882149154816)]} 0 3
[junit4:junit4]   2> 5038 T437 C18 P52643 /update {wt=javabin&version=2} {add=[39 (1412962882150203392)]} 0 3
[junit4:junit4]   2> 5040 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[38 (-1412962882155446272)]} 0 0
[junit4:junit4]   2> 5041 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10041 (-1412962882156494848)]} 0 1
[junit4:junit4]   2> 5045 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5046 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[38 (-1412962882158592000)]} 0 4
[junit4:junit4]   2> 5046 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5048 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10041 (-1412962882158592001)]} 0 5
[junit4:junit4]   2> 5050 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10042 (1412962882165932032)]} 0 1
[junit4:junit4]   2> 5049 T421 C17 P46100 /update {wt=javabin&version=2} {add=[40 (1412962882164883456)]} 0 1
[junit4:junit4]   2> 5056 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5055 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5057 T437 C18 P52643 /update {wt=javabin&version=2} {add=[40 (1412962882169077760)]} 0 5
[junit4:junit4]   2> 5058 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10042 (1412962882169077761)]} 0 6
[junit4:junit4]   2> 5061 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10043 (1412962882176417792)]} 0 1
[junit4:junit4]   2> 5072 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5072 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10043 (1412962882181660672)]} 0 7
[junit4:junit4]   2> 5075 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10044 (1412962882192146432)]} 0 0
[junit4:junit4]   2> 5080 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5081 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10044 (1412962882195292160)]} 0 4
[junit4:junit4]   2> 5083 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10045 (1412962882200535040)]} 0 0
[junit4:junit4]   2> 5086 T421 C17 P46100 /update {wt=javabin&version=2} {add=[41 (1412962882175369216)]} 0 27
[junit4:junit4]   2> 5099 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5102 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10045 (1412962882203680768)]} 0 16
[junit4:junit4]   2> 5105 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10046 (1412962882222555136)]} 0 1
[junit4:junit4]   2> 5110 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5111 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10046 (1412962882226749440)]} 0 3
[junit4:junit4]   2> 5118 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10042 (-1412962882237235200)]} 0 5
[junit4:junit4]   2> 5128 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 5129 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10042 (-1412962882240380928)]} 0 9
[junit4:junit4]   2> 5132 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10047 (1412962882250866688)]} 0 1
[junit4:junit4]   2> 5136 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5137 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10047 (1412962882254012416)]} 0 3
[junit4:junit4]   2> 5139 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10048 (1412962882259255296)]} 0 0
[junit4:junit4]   2> 5155 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=11 
[junit4:junit4]   2> 5156 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10048 (1412962882262401024)]} 0 14
[junit4:junit4]   2> 5159 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10043 (-1412962882280226816)]} 0 1
[junit4:junit4]   2> 5163 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5164 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10043 (-1412962882282323968)]} 0 3
[junit4:junit4]   2> 5167 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5167 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10049 (1412962882288615424)]} 0 0
[junit4:junit4]   2> 5168 T437 C18 P52643 /update {wt=javabin&version=2} {add=[41 (1412962882216263680)]} 0 70
[junit4:junit4]   2> 5171 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[41 (-1412962882292809728)]} 0 1
[junit4:junit4]   2> 5183 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5183 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5184 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10049 (1412962882291761152)]} 0 15
[junit4:junit4]   2> 5185 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[41 (-1412962882303295488)]} 0 5
[junit4:junit4]   2> 5187 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10050 (1412962882308538368)]} 0 1
[junit4:junit4]   2> 5188 T422 C17 P46100 /update {wt=javabin&version=2} {add=[42 (1412962882309586944)]} 0 1
[junit4:junit4]   2> 5194 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5195 T436 C18 P52643 /update {wt=javabin&version=2} {add=[42 (1412962882312732673)]} 0 5
[junit4:junit4]   2> 5198 T422 C17 P46100 /update {wt=javabin&version=2} {add=[43 (1412962882321121280)]} 0 1
[junit4:junit4]   2> 5200 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5201 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10050 (1412962882312732672)]} 0 12
[junit4:junit4]   2> 5203 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10044 (-1412962882326364160)]} 0 0
[junit4:junit4]   2> 5205 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5205 T436 C18 P52643 /update {wt=javabin&version=2} {add=[43 (1412962882325315584)]} 0 3
[junit4:junit4]   2> 5210 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5210 T422 C17 P46100 /update {wt=javabin&version=2} {add=[44 (1412962882332655616)]} 0 1
[junit4:junit4]   2> 5211 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10044 (-1412962882330558464)]} 0 5
[junit4:junit4]   2> 5216 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10051 (1412962882336849920)]} 0 3
[junit4:junit4]   2> 5217 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=3 
[junit4:junit4]   2> 5218 T436 C18 P52643 /update {wt=javabin&version=2} {add=[44 (1412962882335801344)]} 0 6
[junit4:junit4]   2> 5221 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5221 T422 C17 P46100 /update {wt=javabin&version=2} {add=[45 (1412962882344189952)]} 0 1
[junit4:junit4]   2> 5222 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10051 (1412962882342092800)]} 0 4
[junit4:junit4]   2> 5225 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10052 (1412962882348384256)]} 0 1
[junit4:junit4]   2> 5226 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5227 T436 C18 P52643 /update {wt=javabin&version=2} {add=[45 (1412962882347335680)]} 0 4
[junit4:junit4]   2> 5229 T422 C17 P46100 /update {wt=javabin&version=2} {add=[46 (1412962882353627136)]} 0 0
[junit4:junit4]   2> 5229 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5230 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10052 (1412962882351529984)]} 0 3
[junit4:junit4]   2> 5233 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10047 (-1412962882356772864)]} 0 1
[junit4:junit4]   2> 5234 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5235 T436 C18 P52643 /update {wt=javabin&version=2} {add=[46 (1412962882356772864)]} 0 3
[junit4:junit4]   2> 5237 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5237 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[42 (-1412962882362015744)]} 0 0
[junit4:junit4]   2> 5238 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10047 (-1412962882359918592)]} 0 3
[junit4:junit4]   2> 5250 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5250 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[42 (-1412962882364112896)]} 0 11
[junit4:junit4]   2> 5253 T421 C17 P46100 /update {wt=javabin&version=2} {add=[47 (1412962882377744384)]} 0 1
[junit4:junit4]   2> 5253 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10053 (1412962882364112896)]} 0 14
[junit4:junit4]   2> 5257 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5258 T436 C18 P52643 /update {wt=javabin&version=2} {add=[47 (1412962882380890112)]} 0 3
[junit4:junit4]   2> 5258 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5258 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10053 (1412962882380890113)]} 0 3
[junit4:junit4]   2> 5260 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10049 (-1412962882386132993)]} 0 0
[junit4:junit4]   2> 5260 T422 C17 P46100 /update {wt=javabin&version=2} {add=[48 (1412962882386132992)]} 0 1
[junit4:junit4]   2> 5265 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5265 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5265 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10049 (-1412962882388230144)]} 0 3
[junit4:junit4]   2> 5266 T436 C18 P52643 /update {wt=javabin&version=2} {add=[48 (1412962882389278720)]} 0 3
[junit4:junit4]   2> 5268 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10054 (1412962882394521600)]} 0 0
[junit4:junit4]   2> 5269 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[43 (-1412962882395570176)]} 0 0
[junit4:junit4]   2> 5272 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5273 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5273 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[43 (-1412962882397667328)]} 0 2
[junit4:junit4]   2> 5273 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10054 (1412962882396618752)]} 0 3
[junit4:junit4]   2> 5275 T422 C17 P46100 /update {wt=javabin&version=2} {add=[49 (1412962882401861632)]} 0 0
[junit4:junit4]   2> 5276 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10050 (-1412962882402910208)]} 0 1
[junit4:junit4]   2> 5279 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5280 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5280 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10050 (-1412962882403958785)]} 0 3
[junit4:junit4]   2> 5280 T437 C18 P52643 /update {wt=javabin&version=2} {add=[49 (1412962882403958784)]} 0 3
[junit4:junit4]   2> 5283 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10055 (1412962882409201664)]} 0 1
[junit4:junit4]   2> 5285 T422 C17 P46100 /update {wt=javabin&version=2} {add=[50 (1412962882411298816)]} 0 1
[junit4:junit4]   2> 5287 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5288 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10055 (1412962882412347392)]} 0 3
[junit4:junit4]   2> 5289 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5290 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10051 (-1412962882417590272)]} 0 0
[junit4:junit4]   2> 5290 T436 C18 P52643 /update {wt=javabin&version=2} {add=[50 (1412962882414444544)]} 0 3
[junit4:junit4]   2> 5292 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[44 (-1412962882419687424)]} 0 0
[junit4:junit4]   2> 5294 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5294 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10051 (-1412962882419687424)]} 0 2
[junit4:junit4]   2> 5297 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10056 (1412962882423881728)]} 0 1
[junit4:junit4]   2> 5297 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5297 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[44 (-1412962882422833152)]} 0 2
[junit4:junit4]   2> 5300 T422 C17 P46100 /update {wt=javabin&version=2} {add=[51 (1412962882428076032)]} 0 1
[junit4:junit4]   2> 5301 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5302 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10056 (1412962882427027456)]} 0 4
[junit4:junit4]   2> 5307 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10057 (1412962882432270336)]} 0 3
[junit4:junit4]   2> 5307 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=3 
[junit4:junit4]   2> 5308 T436 C18 P52643 /update {wt=javabin&version=2} {add=[51 (1412962882430173184)]} 0 6
[junit4:junit4]   2> 5310 T422 C17 P46100 /update {wt=javabin&version=2} {add=[52 (1412962882437513216)]} 0 1
[junit4:junit4]   2> 5312 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5313 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10057 (1412962882437513216)]} 0 4
[junit4:junit4]   2> 5315 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10053 (-1412962882442756096)]} 0 1
[junit4:junit4]   2> 5317 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5318 T436 C18 P52643 /update {wt=javabin&version=2} {add=[52 (1412962882440658944)]} 0 6
[junit4:junit4]   2> 5318 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5319 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10053 (-1412962882445901824)]} 0 3
[junit4:junit4]   2> 5320 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[47 (-1412962882449047552)]} 0 0
[junit4:junit4]   2> 5321 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10058 (1412962882449047553)]} 0 1
[junit4:junit4]   2> 5323 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5324 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[47 (-1412962882451144704)]} 0 2
[junit4:junit4]   2> 5325 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5326 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10058 (1412962882452193280)]} 0 4
[junit4:junit4]   2> 5328 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10054 (-1412962882457436160)]} 0 0
[junit4:junit4]   2> 5329 T421 C17 P46100 /update {wt=javabin&version=2} {add=[53 (1412962882455339008)]} 0 3
[junit4:junit4]   2> 5332 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5332 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10054 (-1412962882459533312)]} 0 2
[junit4:junit4]   2> 5336 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10059 (1412962882464776192)]} 0 2
[junit4:junit4]   2> 5336 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5336 T437 C18 P52643 /update {wt=javabin&version=2} {add=[53 (1412962882460581888)]} 0 5
[junit4:junit4]   2> 5338 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[49 (-1412962882467921920)]} 0 0
[junit4:junit4]   2> 5340 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5340 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10059 (1412962882467921920)]} 0 2
[junit4:junit4]   2> 5342 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5343 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[49 (-1412962882470019072)]} 0 3
[junit4:junit4]   2> 5343 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10060 (1412962882472116224)]} 0 1
[junit4:junit4]   2> 5345 T422 C17 P46100 /update {wt=javabin&version=2} {add=[54 (1412962882475261952)]} 0 0
[junit4:junit4]   2> 5349 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5349 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10060 (1412962882476310528)]} 0 3
[junit4:junit4]   2> 5351 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5351 T436 C18 P52643 /update {wt=javabin&version=2} {add=[54 (1412962882478407680)]} 0 3
[junit4:junit4]   2> 5352 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10061 (1412962882481553408)]} 0 1
[junit4:junit4]   2> 5353 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[50 (-1412962882483650560)]} 0 0
[junit4:junit4]   2> 5356 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5356 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10061 (1412962882484699136)]} 0 2
[junit4:junit4]   2> 5357 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5357 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[50 (-1412962882485747712)]} 0 2
[junit4:junit4]   2> 5359 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10055 (-1412962882488893440)]} 0 1
[junit4:junit4]   2> 5360 T422 C17 P46100 /update {wt=javabin&version=2} {add=[55 (1412962882489942016)]} 0 1
[junit4:junit4]   2> 5362 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5363 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10055 (-1412962882492039168)]} 0 3
[junit4:junit4]   2> 5364 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5365 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10062 (1412962882496233472)]} 0 0
[junit4:junit4]   2> 5366 T436 C18 P52643 /update {wt=javabin&version=2} {add=[55 (1412962882493087744)]} 0 4
[junit4:junit4]   2> 5368 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[51 (-1412962882499379200)]} 0 0
[junit4:junit4]   2> 5373 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=3 
[junit4:junit4]   2> 5374 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5374 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10062 (1412962882499379200)]} 0 6
[junit4:junit4]   2> 5374 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[51 (-1412962882503573504)]} 0 3
[junit4:junit4]   2> 5376 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10056 (-1412962882507767808)]} 0 0
[junit4:junit4]   2> 5377 T421 C17 P46100 /update {wt=javabin&version=2} {add=[56 (1412962882508816384)]} 0 1
[junit4:junit4]   2> 5380 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5381 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10056 (-1412962882509864960)]} 0 3
[junit4:junit4]   2> 5381 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5382 T436 C18 P52643 /update {wt=javabin&version=2} {add=[56 (1412962882510913536)]} 0 3
[junit4:junit4]   2> 5383 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10063 (1412962882515107840)]} 0 0
[junit4:junit4]   2> 5384 T422 C17 P46100 /update {wt=javabin&version=2} {add=[57 (1412962882516156416)]} 0 1
[junit4:junit4]   2> 5387 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5388 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5388 T437 C18 P52643 /update {wt=javabin&version=2} {add=[57 (1412962882518253568)]} 0 3
[junit4:junit4]   2> 5388 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10063 (1412962882517204992)]} 0 3
[junit4:junit4]   2> 5390 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[53 (-1412962882522447872)]} 0 0
[junit4:junit4]   2> 5390 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10064 (1412962882522447873)]} 0 0
[junit4:junit4]   2> 5394 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5394 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5394 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[53 (-1412962882524545024)]} 0 2
[junit4:junit4]   2> 5395 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10064 (1412962882524545025)]} 0 3
[junit4:junit4]   2> 5397 T421 C17 P46100 /update {wt=javabin&version=2} {add=[58 (1412962882528739328)]} 0 1
[junit4:junit4]   2> 5398 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10065 (1412962882530836480)]} 0 1
[junit4:junit4]   2> 5401 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5401 T437 C18 P52643 /update {wt=javabin&version=2} {add=[58 (1412962882531885056)]} 0 2
[junit4:junit4]   2> 5402 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5402 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10065 (1412962882532933632)]} 0 2
[junit4:junit4]   2> 5404 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[54 (-1412962882537127936)]} 0 1
[junit4:junit4]   2> 5404 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10066 (1412962882537127937)]} 0 0
[junit4:junit4]   2> 5407 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5408 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5408 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[54 (-1412962882539225088)]} 0 2
[junit4:junit4]   2> 5409 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10066 (1412962882539225089)]} 0 3
[junit4:junit4]   2> 5411 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10057 (-1412962882543419393)]} 0 1
[junit4:junit4]   2> 5411 T421 C17 P46100 /update {wt=javabin&version=2} {add=[59 (1412962882543419392)]} 0 1
[junit4:junit4]   2> 5414 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5415 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10057 (-1412962882546565120)]} 0 2
[junit4:junit4]   2> 5417 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10067 (1412962882549710848)]} 0 1
[junit4:junit4]   2> 5417 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5418 T436 C18 P52643 /update {wt=javabin&version=2} {add=[59 (1412962882546565121)]} 0 5
[junit4:junit4]   2> 5421 T421 C17 P46100 /update {wt=javabin&version=2} {add=[60 (1412962882552856576)]} 0 2
[junit4:junit4]   2> 5421 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5422 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10067 (1412962882552856576)]} 0 3
[junit4:junit4]   2> 5424 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10060 (-1412962882558099456)]} 0 0
[junit4:junit4]   2> 5426 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5426 T436 C18 P52643 /update {wt=javabin&version=2} {add=[60 (1412962882557050880)]} 0 3
[junit4:junit4]   2> 5427 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5428 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10060 (-1412962882560196608)]} 0 2
[junit4:junit4]   2> 5429 T421 C17 P46100 /update {wt=javabin&version=2} {add=[61 (1412962882562293760)]} 0 1
[junit4:junit4]   2> 5430 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10068 (1412962882564390912)]} 0 0
[junit4:junit4]   2> 5433 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5433 T437 C18 P52643 /update {wt=javabin&version=2} {add=[61 (1412962882565439488)]} 0 2
[junit4:junit4]   2> 5434 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5434 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10068 (1412962882566488064)]} 0 2
[junit4:junit4]   2> 5435 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[55 (-1412962882569633792)]} 0 0
[junit4:junit4]   2> 5436 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10069 (1412962882570682368)]} 0 0
[junit4:junit4]   2> 5439 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5439 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[55 (-1412962882571730944)]} 0 2
[junit4:junit4]   2> 5441 T421 C17 P46100 /update {wt=javabin&version=2} {add=[62 (1412962882575925248)]} 0 0
[junit4:junit4]   2> 5441 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5442 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10069 (1412962882572779520)]} 0 4
[junit4:junit4]   2> 5444 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10070 (1412962882579070976)]} 0 0
[junit4:junit4]   2> 5446 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5446 T436 C18 P52643 /update {wt=javabin&version=2} {add=[62 (1412962882579070976)]} 0 3
[junit4:junit4]   2> 5448 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[56 (-1412962882583265280)]} 0 0
[junit4:junit4]   2> 5448 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5449 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10070 (1412962882581168128)]} 0 3
[junit4:junit4]   2> 5451 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10063 (-1412962882586411008)]} 0 0
[junit4:junit4]   2> 5452 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5452 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[56 (-1412962882585362432)]} 0 2
[junit4:junit4]   2> 5454 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5454 T421 C17 P46100 /update {wt=javabin&version=2} {add=[63 (1412962882589556736)]} 0 0
[junit4:junit4]   2> 5455 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10063 (-1412962882588508160)]} 0 2
[junit4:junit4]   2> 5457 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10071 (1412962882592702464)]} 0 0
[junit4:junit4]   2> 5459 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5461 T436 C18 P52643 /update {wt=javabin&version=2} {add=[63 (1412962882591653888)]} 0 5
[junit4:junit4]   2> 5463 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5464 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10071 (1412962882594799616)]} 0 5
[junit4:junit4]   2> 5464 T421 C17 P46100 /update {wt=javabin&version=2} {add=[64 (1412962882600042496)]} 0 2
[junit4:junit4]   2> 5466 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10072 (1412962882602139648)]} 0 1
[junit4:junit4]   2> 5468 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5469 T437 C18 P52643 /update {wt=javabin&version=2} {add=[64 (1412962882602139648)]} 0 3
[junit4:junit4]   2> 5471 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5473 T422 C17 P46100 /update {wt=javabin&version=2} {add=[65 (1412962882606333952)]} 0 3
[junit4:junit4]   2> 5473 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10072 (1412962882604236800)]} 0 5
[junit4:junit4]   2> 5475 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10066 (-1412962882611576832)]} 0 0
[junit4:junit4]   2> 5477 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5478 T437 C18 P52643 /update {wt=javabin&version=2} {add=[65 (1412962882611576832)]} 0 3
[junit4:junit4]   2> 5479 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5479 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10066 (-1412962882613673984)]} 0 2
[junit4:junit4]   2> 5481 T422 C17 P46100 /update {wt=javabin&version=2} {add=[66 (1412962882616819712)]} 0 1
[junit4:junit4]   2> 5481 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10073 (1412962882617868288)]} 0 0
[junit4:junit4]   2> 5485 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5486 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5486 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10073 (1412962882619965441)]} 0 3
[junit4:junit4]   2> 5486 T436 C18 P52643 /update {wt=javabin&version=2} {add=[66 (1412962882619965440)]} 0 4
[junit4:junit4]   2> 5488 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10074 (1412962882625208320)]} 0 0
[junit4:junit4]   2> 5488 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[57 (-1412962882625208321)]} 0 0
[junit4:junit4]   2> 5492 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5492 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5492 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[57 (-1412962882627305473)]} 0 2
[junit4:junit4]   2> 5493 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10074 (1412962882627305472)]} 0 3
[junit4:junit4]   2> 5494 T422 C17 P46100 /update {wt=javabin&version=2} {add=[67 (1412962882631499776)]} 0 0
[junit4:junit4]   2> 5496 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10075 (1412962882631499777)]} 0 2
[junit4:junit4]   2> 5499 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5499 T436 C18 P52643 /update {wt=javabin&version=2} {add=[67 (1412962882633596928)]} 0 3
[junit4:junit4]   2> 5500 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5500 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10075 (1412962882635694080)]} 0 2
[junit4:junit4]   2> 5501 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[60 (-1412962882638839808)]} 0 0
[junit4:junit4]   2> 5502 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10067 (-1412962882639888384)]} 0 0
[junit4:junit4]   2> 5506 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5506 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5506 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10067 (-1412962882641985537)]} 0 2
[junit4:junit4]   2> 5506 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[60 (-1412962882641985536)]} 0 3
[junit4:junit4]   2> 5509 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10076 (1412962882646179840)]} 0 1
[junit4:junit4]   2> 5509 T421 C17 P46100 /update {wt=javabin&version=2} {add=[68 (1412962882646179841)]} 0 1
[junit4:junit4]   2> 5512 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5513 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5513 T436 C18 P52643 /update {wt=javabin&version=2} {add=[68 (1412962882649325568)]} 0 2
[junit4:junit4]   2> 5513 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10076 (1412962882648276992)]} 0 3
[junit4:junit4]   2> 5515 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10077 (1412962882653519873)]} 0 0
[junit4:junit4]   2> 5515 T421 C17 P46100 /update {wt=javabin&version=2} {add=[69 (1412962882653519872)]} 0 0
[junit4:junit4]   2> 5519 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5520 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10077 (1412962882655617024)]} 0 3
[junit4:junit4]   2> 5522 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5523 T436 C18 P52643 /update {wt=javabin&version=2} {add=[69 (1412962882655617025)]} 0 6
[junit4:junit4]   2> 5525 T422 C17 P46100 /update {wt=javabin&version=2} {add=[70 (1412962882664005632)]} 0 0
[junit4:junit4]   2> 5522 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10068 (-1412962882660859904)]} 0 0
[junit4:junit4]   2> 5531 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5532 T436 C18 P52643 /update {wt=javabin&version=2} {add=[70 (1412962882667151360)]} 0 4
[junit4:junit4]   2> 5534 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[63 (-1412962882673442816)]} 0 0
[junit4:junit4]   2> 5538 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5538 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[63 (-1412962882675539968)]} 0 2
[junit4:junit4]   2> 5540 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5540 T421 C17 P46100 /update {wt=javabin&version=2} {add=[71 (1412962882679734272)]} 0 0
[junit4:junit4]   2> 5541 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10068 (-1412962882678685696)]} 0 2
[junit4:junit4]   2> 5543 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10078 (1412962882682880000)]} 0 0
[junit4:junit4]   2> 5573 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=28 
[junit4:junit4]   2> 5574 T436 C18 P52643 /update {wt=javabin&version=2} {add=[71 (1412962882682880000)]} 0 32
[junit4:junit4]   2> 5576 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5576 T421 C17 P46100 /update {wt=javabin&version=2} {add=[72 (1412962882717483008)]} 0 0
[junit4:junit4]   2> 5576 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10078 (1412962882715385856)]} 0 31
[junit4:junit4]   2> 5578 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10075 (-1412962882719580160)]} 0 0
[junit4:junit4]   2> 5582 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5582 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5582 T436 C18 P52643 /update {wt=javabin&version=2} {add=[72 (1412962882719580160)]} 0 4
[junit4:junit4]   2> 5582 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10075 (-1412962882721677312)]} 0 2
[junit4:junit4]   2> 5584 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[66 (-1412962882725871616)]} 0 0
[junit4:junit4]   2> 5585 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10079 (1412962882725871617)]} 0 1
[junit4:junit4]   2> 5588 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5589 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[66 (-1412962882727968769)]} 0 3
[junit4:junit4]   2> 5589 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5589 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10079 (1412962882727968768)]} 0 3
[junit4:junit4]   2> 5591 T422 C17 P46100 /update {wt=javabin&version=2} {add=[73 (1412962882733211648)]} 0 0
[junit4:junit4]   2> 5591 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10076 (-1412962882733211649)]} 0 0
[junit4:junit4]   2> 5595 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5595 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5595 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10076 (-1412962882735308801)]} 0 2
[junit4:junit4]   2> 5596 T436 C18 P52643 /update {wt=javabin&version=2} {add=[73 (1412962882735308800)]} 0 3
[junit4:junit4]   2> 5598 T422 C17 P46100 /update {wt=javabin&version=2} {add=[74 (1412962882739503105)]} 0 1
[junit4:junit4]   2> 5598 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10080 (1412962882739503104)]} 0 1
[junit4:junit4]   2> 5602 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5602 T436 C18 P52643 /update {wt=javabin&version=2} {add=[74 (1412962882741600256)]} 0 3
[junit4:junit4]   2> 5605 T421 C17 P46100 /update {wt=javabin&version=2} {add=[75 (1412962882746843136)]} 0 1
[junit4:junit4]   2> 5617 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5618 T436 C18 P52643 /update {wt=javabin&version=2} {add=[75 (1412962882754183168)]} 0 7
[junit4:junit4]   2> 5619 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=18 
[junit4:junit4]   2> 5620 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10080 (1412962882742648832)]} 0 20
[junit4:junit4]   2> 5620 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[67 (-1412962882763620352)]} 0 0
[junit4:junit4]   2> 5622 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10081 (1412962882765717504)]} 0 0
[junit4:junit4]   2> 5625 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5625 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[67 (-1412962882766766080)]} 0 2
[junit4:junit4]   2> 5627 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5627 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10081 (1412962882768863232)]} 0 3
[junit4:junit4]   2> 5627 T421 C17 P46100 /update {wt=javabin&version=2} {add=[76 (1412962882770960384)]} 0 0
[junit4:junit4]   2> 5629 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10079 (-1412962882773057536)]} 0 0
[junit4:junit4]   2> 5633 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5634 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10079 (-1412962882775154688)]} 0 3
[junit4:junit4]   2> 5637 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10082 (1412962882780397568)]} 0 1
[junit4:junit4]   2> 5641 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5642 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10082 (1412962882783543296)]} 0 3
[junit4:junit4]   2> 5644 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10080 (-1412962882788786176)]} 0 0
[junit4:junit4]   2> 5648 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5649 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10080 (-1412962882791931904)]} 0 2
[junit4:junit4]   2> 5654 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5656 T437 C18 P52643 /update {wt=javabin&version=2} {add=[76 (1412962882773057536)]} 0 27
[junit4:junit4]   2> 5665 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10083 (1412962882796126208)]} 0 14
[junit4:junit4]   2> 5666 T421 C17 P46100 /update {wt=javabin&version=2} {add=[77 (1412962882803466240)]} 0 8
[junit4:junit4]   2> 5670 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5671 T436 C18 P52643 /update {wt=javabin&version=2} {add=[77 (1412962882813952000)]} 0 3
[junit4:junit4]   2> 5671 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5672 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10083 (1412962882813952001)]} 0 4
[junit4:junit4]   2> 5673 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[68 (-1412962882819194880)]} 0 0
[junit4:junit4]   2> 5674 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10081 (-1412962882820243456)]} 0 0
[junit4:junit4]   2> 5677 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5677 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5678 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[68 (-1412962882821292032)]} 0 3
[junit4:junit4]   2> 5678 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10081 (-1412962882822340608)]} 0 2
[junit4:junit4]   2> 5681 T421 C17 P46100 /update {wt=javabin&version=2} {add=[78 (1412962882827583489)]} 0 1
[junit4:junit4]   2> 5681 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10084 (1412962882827583488)]} 0 1
[junit4:junit4]   2> 5687 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5688 T437 C18 P52643 /update {wt=javabin&version=2} {add=[78 (1412962882830729216)]} 0 5
[junit4:junit4]   2> 5690 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[75 (-1412962882837020672)]} 0 0
[junit4:junit4]   2> 5692 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5692 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10084 (1412962882830729217)]} 0 8
[junit4:junit4]   2> 5693 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5694 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10083 (-1412962882841214976)]} 0 0
[junit4:junit4]   2> 5694 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[75 (-1412962882839117824)]} 0 2
[junit4:junit4]   2> 5696 T422 C17 P46100 /update {wt=javabin&version=2} {add=[79 (1412962882843312128)]} 0 0
[junit4:junit4]   2> 5699 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5699 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10083 (-1412962882843312128)]} 0 3
[junit4:junit4]   2> 5701 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5701 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10085 (1412962882848555008)]} 0 0
[junit4:junit4]   2> 5702 T437 C18 P52643 /update {wt=javabin&version=2} {add=[79 (1412962882845409280)]} 0 4
[junit4:junit4]   2> 5704 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[76 (-1412962882851700736)]} 0 0
[junit4:junit4]   2> 5706 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5706 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10085 (1412962882850652160)]} 0 3
[junit4:junit4]   2> 5707 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5708 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[76 (-1412962882853797888)]} 0 2
[junit4:junit4]   2> 5708 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10086 (1412962882855895040)]} 0 0
[junit4:junit4]   2> 5710 T421 C17 P46100 /update {wt=javabin&version=2} {add=[80 (1412962882856943616)]} 0 1
[junit4:junit4]   2> 5712 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5712 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10086 (1412962882857992192)]} 0 2
[junit4:junit4]   2> 5713 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5713 T436 C18 P52643 /update {wt=javabin&version=2} {add=[80 (1412962882859040768)]} 0 2
[junit4:junit4]   2> 5714 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10085 (-1412962882862186496)]} 0 0
[junit4:junit4]   2> 5715 T422 C17 P46100 /update {wt=javabin&version=2} {add=[81 (1412962882863235072)]} 0 0
[junit4:junit4]   2> 5718 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5718 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10085 (-1412962882864283648)]} 0 2
[junit4:junit4]   2> 5719 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5720 T437 C18 P52643 /update {wt=javabin&version=2} {add=[81 (1412962882865332224)]} 0 3
[junit4:junit4]   2> 5720 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10087 (1412962882868477952)]} 0 0
[junit4:junit4]   2> 5722 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[79 (-1412962882869526528)]} 0 1
[junit4:junit4]   2> 5724 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5724 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10087 (1412962882870575104)]} 0 2
[junit4:junit4]   2> 5725 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5725 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[79 (-1412962882871623680)]} 0 2
[junit4:junit4]   2> 5726 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10086 (-1412962882874769408)]} 0 0
[junit4:junit4]   2> 5727 T422 C17 P46100 /update {wt=javabin&version=2} {add=[82 (1412962882875817984)]} 0 0
[junit4:junit4]   2> 5730 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5730 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10086 (-1412962882876866560)]} 0 2
[junit4:junit4]   2> 5731 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5731 T437 C18 P52643 /update {wt=javabin&version=2} {add=[82 (1412962882877915136)]} 0 2
[junit4:junit4]   2> 5732 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10088 (1412962882881060864)]} 0 0
[junit4:junit4]   2> 5733 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[80 (-1412962882882109440)]} 0 0
[junit4:junit4]   2> 5736 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5737 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10088 (1412962882883158016)]} 0 3
[junit4:junit4]   2> 5737 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5738 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[80 (-1412962882884206592)]} 0 3
[junit4:junit4]   2> 5739 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10087 (-1412962882888400896)]} 0 0
[junit4:junit4]   2> 5740 T422 C17 P46100 /update {wt=javabin&version=2} {add=[83 (1412962882889449472)]} 0 0
[junit4:junit4]   2> 5743 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5744 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5744 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10087 (-1412962882890498048)]} 0 3
[junit4:junit4]   2> 5745 T437 C18 P52643 /update {wt=javabin&version=2} {add=[83 (1412962882891546624)]} 0 3
[junit4:junit4]   2> 5747 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[81 (-1412962882895740928)]} 0 1
[junit4:junit4]   2> 5750 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10089 (1412962882895740929)]} 0 4
[junit4:junit4]   2> 5752 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5752 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[81 (-1412962882898886656)]} 0 3
[junit4:junit4]   2> 5754 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5754 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10089 (1412962882902032384)]} 0 2
[junit4:junit4]   2> 5755 T422 C17 P46100 /update {wt=javabin&version=2} {add=[84 (1412962882904129536)]} 0 1
[junit4:junit4]   2> 5756 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10090 (1412962882906226688)]} 0 0
[junit4:junit4]   2> 5758 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5759 T436 C18 P52643 /update {wt=javabin&version=2} {add=[84 (1412962882907275264)]} 0 2
[junit4:junit4]   2> 5761 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[83 (-1412962882911469568)]} 0 0
[junit4:junit4]   2> 5762 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5762 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10090 (1412962882908323840)]} 0 4
[junit4:junit4]   2> 5764 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5765 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10091 (1412962882914615296)]} 0 0
[junit4:junit4]   2> 5765 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[83 (-1412962882913566720)]} 0 2
[junit4:junit4]   2> 5767 T421 C17 P46100 /update {wt=javabin&version=2} {add=[85 (1412962882917761024)]} 0 1
[junit4:junit4]   2> 5769 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5770 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10091 (1412962882917761024)]} 0 4
[junit4:junit4]   2> 5771 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5771 T436 C18 P52643 /update {wt=javabin&version=2} {add=[85 (1412962882919858176)]} 0 2
[junit4:junit4]   2> 5772 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10088 (-1412962882921955328)]} 0 1
[junit4:junit4]   2> 5773 T422 C17 P46100 /update {wt=javabin&version=2} {add=[86 (1412962882924052480)]} 0 0
[junit4:junit4]   2> 5775 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5776 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10088 (-1412962882924052480)]} 0 3
[junit4:junit4]   2> 5777 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5778 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10092 (1412962882928246784)]} 0 1
[junit4:junit4]   2> 5778 T437 C18 P52643 /update {wt=javabin&version=2} {add=[86 (1412962882926149632)]} 0 3
[junit4:junit4]   2> 5780 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[85 (-1412962882931392512)]} 0 0
[junit4:junit4]   2> 5783 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5783 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10092 (1412962882931392512)]} 0 3
[junit4:junit4]   2> 5784 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5784 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[85 (-1412962882933489664)]} 0 2
[junit4:junit4]   2> 5785 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10089 (-1412962882936635392)]} 0 0
[junit4:junit4]   2> 5786 T421 C17 P46100 /update {wt=javabin&version=2} {add=[87 (1412962882937683968)]} 0 0
[junit4:junit4]   2> 5789 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5790 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10089 (-1412962882939781120)]} 0 3
[junit4:junit4]   2> 5790 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5791 T436 C18 P52643 /update {wt=javabin&version=2} {add=[87 (1412962882939781121)]} 0 3
[junit4:junit4]   2> 5792 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10093 (1412962882943975424)]} 0 1
[junit4:junit4]   2> 5792 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[86 (-1412962882943975425)]} 0 0
[junit4:junit4]   2> 5795 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5796 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10093 (1412962882946072576)]} 0 2
[junit4:junit4]   2> 5796 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5796 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[86 (-1412962882946072577)]} 0 2
[junit4:junit4]   2> 5798 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10091 (-1412962882949218304)]} 0 1
[junit4:junit4]   2> 5798 T421 C17 P46100 /update {wt=javabin&version=2} {add=[88 (1412962882950266880)]} 0 0
[junit4:junit4]   2> 5801 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5801 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10091 (-1412962882951315456)]} 0 2
[junit4:junit4]   2> 5802 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5802 T436 C18 P52643 /update {wt=javabin&version=2} {add=[88 (1412962882952364032)]} 0 2
[junit4:junit4]   2> 5804 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10094 (1412962882955509760)]} 0 1
[junit4:junit4]   2> 5804 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[87 (-1412962882956558336)]} 0 0
[junit4:junit4]   2> 5808 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5808 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[87 (-1412962882958655488)]} 0 2
[junit4:junit4]   2> 5809 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5809 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10094 (1412962882957606912)]} 0 4
[junit4:junit4]   2> 5810 T422 C17 P46100 /update {wt=javabin&version=2} {add=[89 (1412962882962849792)]} 0 0
[junit4:junit4]   2> 5813 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10095 (1412962882963898368)]} 0 2
[junit4:junit4]   2> 5814 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5814 T436 C18 P52643 /update {wt=javabin&version=2} {add=[89 (1412962882964946944)]} 0 2
[junit4:junit4]   2> 5817 T421 C17 P46100 /update {wt=javabin&version=2} {add=[90 (1412962882969141248)]} 0 1
[junit4:junit4]   2> 5819 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5820 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10095 (1412962882968092672)]} 0 5
[junit4:junit4]   2> 5821 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5821 T436 C18 P52643 /update {wt=javabin&version=2} {add=[90 (1412962882971238400)]} 0 3
[junit4:junit4]   2> 5822 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10096 (1412962882975432704)]} 0 0
[junit4:junit4]   2> 5823 T422 C17 P46100 /update {wt=javabin&version=2} {add=[91 (1412962882976481280)]} 0 0
[junit4:junit4]   2> 5826 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5826 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10096 (1412962882977529856)]} 0 2
[junit4:junit4]   2> 5827 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5828 T437 C18 P52643 /update {wt=javabin&version=2} {add=[91 (1412962882978578432)]} 0 3
[junit4:junit4]   2> 5828 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10095 (-1412962882981724160)]} 0 0
[junit4:junit4]   2> 5829 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[88 (-1412962882982772736)]} 0 0
[junit4:junit4]   2> 5831 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5832 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10095 (-1412962882983821312)]} 0 2
[junit4:junit4]   2> 5833 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5833 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[88 (-1412962882984869888)]} 0 2
[junit4:junit4]   2> 5834 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10097 (1412962882988015616)]} 0 0
[junit4:junit4]   2> 5835 T422 C17 P46100 /update {wt=javabin&version=2} {add=[92 (1412962882989064192)]} 0 0
[junit4:junit4]   2> 5838 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5838 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10097 (1412962882990112768)]} 0 2
[junit4:junit4]   2> 5839 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5840 T437 C18 P52643 /update {wt=javabin&version=2} {add=[92 (1412962882991161344)]} 0 3
[junit4:junit4]   2> 5840 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10098 (1412962882994307072)]} 0 0
[junit4:junit4]   2> 5841 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[89 (-1412962882995355648)]} 0 0
[junit4:junit4]   2> 5845 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5846 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10098 (1412962882996404224)]} 0 4
[junit4:junit4]   2> 5846 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5847 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[89 (-1412962882998501376)]} 0 3
[junit4:junit4]   2> 5848 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10096 (-1412962883002695680)]} 0 0
[junit4:junit4]   2> 5849 T422 C17 P46100 /update {wt=javabin&version=2} {add=[93 (1412962883002695681)]} 0 1
[junit4:junit4]   2> 5851 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5852 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10096 (-1412962883004792832)]} 0 2
[junit4:junit4]   2> 5853 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5853 T437 C18 P52643 /update {wt=javabin&version=2} {add=[93 (1412962883005841408)]} 0 3
[junit4:junit4]   2> 5854 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10099 (1412962883007938560)]} 0 1
[junit4:junit4]   2> 5855 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[91 (-1412962883010035712)]} 0 0
[junit4:junit4]   2> 5857 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5858 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10099 (1412962883010035712)]} 0 3
[junit4:junit4]   2> 5858 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5859 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[91 (-1412962883012132864)]} 0 2
[junit4:junit4]   2> 5860 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10097 (-1412962883015278592)]} 0 0
[junit4:junit4]   2> 5862 T422 C17 P46100 /update {wt=javabin&version=2} {add=[94 (1412962883016327168)]} 0 2
[junit4:junit4]   2> 5863 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5864 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10097 (-1412962883017375744)]} 0 2
[junit4:junit4]   2> 5866 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5866 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10100 (1412962883020521472)]} 0 1
[junit4:junit4]   2> 5867 T437 C18 P52643 /update {wt=javabin&version=2} {add=[94 (1412962883019472896)]} 0 3
[junit4:junit4]   2> 5869 T422 C17 P46100 /update {wt=javabin&version=2} {add=[95 (1412962883023667200)]} 0 1
[junit4:junit4]   2> 5871 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5873 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10100 (1412962883023667200)]} 0 5
[junit4:junit4]   2> 5875 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10098 (-1412962883031007232)]} 0 0
[junit4:junit4]   2> 5875 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5876 T437 C18 P52643 /update {wt=javabin&version=2} {add=[95 (1412962883026812928)]} 0 5
[junit4:junit4]   2> 5878 T422 C17 P46100 /update {wt=javabin&version=2} {add=[96 (1412962883034152960)]} 0 0
[junit4:junit4]   2> 5880 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5880 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10098 (-1412962883034152960)]} 0 3
[junit4:junit4]   2> 5883 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10101 (1412962883038347264)]} 0 1
[junit4:junit4]   2> 5887 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5888 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10101 (1412962883041492992)]} 0 3
[junit4:junit4]   2> 5888 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5891 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10102 (1412962883046735872)]} 0 1
[junit4:junit4]   2> 5891 T437 C18 P52643 /update {wt=javabin&version=2} {add=[96 (1412962883037298688)]} 0 10
[junit4:junit4]   2> 5893 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[95 (-1412962883049881600)]} 0 0
[junit4:junit4]   2> 5896 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5897 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5897 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10102 (1412962883049881600)]} 0 4
[junit4:junit4]   2> 5898 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[95 (-1412962883051978752)]} 0 3
[junit4:junit4]   2> 5900 T421 C17 P46100 /update {wt=javabin&version=2} {add=[97 (1412962883057221633)]} 0 1
[junit4:junit4]   2> 5900 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10103 (1412962883057221632)]} 0 1
[junit4:junit4]   2> 5904 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5904 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5905 T437 C18 P52643 /update {wt=javabin&version=2} {add=[97 (1412962883059318784)]} 0 3
[junit4:junit4]   2> 5905 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10103 (1412962883059318785)]} 0 3
[junit4:junit4]   2> 5907 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10103 (-1412962883064561664)]} 0 0
[junit4:junit4]   2> 5908 T422 C17 P46100 /update {wt=javabin&version=2} {add=[98 (1412962883064561665)]} 0 1
[junit4:junit4]   2> 5911 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5912 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5912 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10103 (-1412962883066658816)]} 0 3
[junit4:junit4]   2> 5912 T437 C18 P52643 /update {wt=javabin&version=2} {add=[98 (1412962883067707392)]} 0 3
[junit4:junit4]   2> 5914 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[96 (-1412962883071901697)]} 0 0
[junit4:junit4]   2> 5914 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10104 (1412962883071901696)]} 0 0
[junit4:junit4]   2> 5918 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5919 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5919 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[96 (-1412962883073998848)]} 0 3
[junit4:junit4]   2> 5919 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10104 (1412962883073998849)]} 0 3
[junit4:junit4]   2> 5921 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10105 (1412962883079241728)]} 0 0
[junit4:junit4]   2> 5923 T422 C17 P46100 /update {wt=javabin&version=2} {add=[99 (1412962883080290304)]} 0 1
[junit4:junit4]   2> 5927 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5928 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5929 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10105 (1412962883082387456)]} 0 5
[junit4:junit4]   2> 5929 T437 C18 P52643 /update {wt=javabin&version=2} {add=[99 (1412962883083436032)]} 0 4
[junit4:junit4]   2> 5931 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[97 (-1412962883089727488)]} 0 0
[junit4:junit4]   2> 5936 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10106 (1412962883089727489)]} 0 5
[junit4:junit4]   2> 5937 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5937 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[97 (-1412962883091824640)]} 0 4
[junit4:junit4]   2> 5940 T422 C17 P46100 /update {wt=javabin&version=2} {add=[100 (1412962883098116096)]} 0 1
[junit4:junit4]   2> 5941 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5942 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10106 (1412962883097067520)]} 0 4
[junit4:junit4]   2> 5945 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10107 (1412962883103358976)]} 0 1
[junit4:junit4]   2> 5946 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5946 T437 C18 P52643 /update {wt=javabin&version=2} {add=[100 (1412962883103358976)]} 0 3
[junit4:junit4]   2> 5948 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[98 (-1412962883107553280)]} 0 0
[junit4:junit4]   2> 5949 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5949 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10107 (1412962883106504704)]} 0 3
[junit4:junit4]   2> 5951 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10104 (-1412962883110699008)]} 0 0
[junit4:junit4]   2> 5952 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5953 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[98 (-1412962883110699008)]} 0 3
[junit4:junit4]   2> 5955 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5956 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10104 (-1412962883112796160)]} 0 3
[junit4:junit4]   2> 5958 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10108 (1412962883118039040)]} 0 1
[junit4:junit4]   2> 5959 T422 C17 P46100 /update {wt=javabin&version=2} {add=[101 (1412962883114893312)]} 0 4
[junit4:junit4]   2> 5962 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5963 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10108 (1412962883120136192)]} 0 3
[junit4:junit4]   2> 5965 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10105 (-1412962883125379072)]} 0 0
[junit4:junit4]   2> 5965 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5966 T437 C18 P52643 /update {wt=javabin&version=2} {add=[101 (1412962883121184768)]} 0 5
[junit4:junit4]   2> 5968 T422 C17 P46100 /update {wt=javabin&version=2} {add=[102 (1412962883128524800)]} 0 0
[junit4:junit4]   2> 5969 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5969 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10105 (-1412962883127476224)]} 0 2
[junit4:junit4]   2> 5972 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10109 (1412962883131670528)]} 0 1
[junit4:junit4]   2> 5973 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5974 T437 C18 P52643 /update {wt=javabin&version=2} {add=[102 (1412962883131670528)]} 0 4
[junit4:junit4]   2> 5976 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5976 T422 C17 P46100 /update {wt=javabin&version=2} {add=[103 (1412962883136913408)]} 0 0
[junit4:junit4]   2> 5977 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10109 (1412962883134816256)]} 0 3
[junit4:junit4]   2> 5979 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10110 (1412962883140059136)]} 0 1
[junit4:junit4]   2> 5981 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5981 T437 C18 P52643 /update {wt=javabin&version=2} {add=[103 (1412962883140059136)]} 0 3
[junit4:junit4]   2> 5983 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5983 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[103 (-1412962883144253440)]} 0 0
[junit4:junit4]   2> 5984 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10110 (1412962883142156288)]} 0 3
[junit4:junit4]   2> 5985 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10107 (-1412962883146350592)]} 0 0
[junit4:junit4]   2> 5987 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5987 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[103 (-1412962883146350592)]} 0 2
[junit4:junit4]   2> 5989 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5989 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[10107 (-1412962883148447744)]} 0 2
[junit4:junit4]   2> 5991 T422 C17 P46100 /update {wt=javabin&version=2} {add=[104 (1412962883151593472)]} 0 0
[junit4:junit4]   2> 5992 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10111 (1412962883153690624)]} 0 1
[junit4:junit4]   2> 5995 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5996 T436 C18 P52643 /update {wt=javabin&version=2} {add=[104 (1412962883154739200)]} 0 3
[junit4:junit4]   2> 5998 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5998 T421 C17 P46100 /update {wt=javabin&version=2} {add=[105 (1412962883159982080)]} 0 0
[junit4:junit4]   2> 5999 T437 C18 P52643 /update {wt=javabin&version=2} {add=[10111 (1412962883155787776)]} 0 5
[junit4:junit4]   2> 6001 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[10110 (-1412962883163127808)]} 0 0
[junit4:junit4]   2> 6003 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6003 T436 C18 P52643 /update {wt=javabin&version=2} {add=[105 (1412962883162079232)]} 0 3
[junit4:junit4]   2> 6005 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6007 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10110 (-1412962883165224960)]} 0 4
[junit4:junit4]   2> 6008 T421 C17 P46100 /update {wt=javabin&version=2} {add=[106 (1412962883169419264)]} 0 1
[junit4:junit4]   2> 6009 T422 C17 P46100 /update {wt=javabin&version=2} {add=[10112 (1412962883171516416)]} 0 0
[junit4:junit4]   2> 6014 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6014 T436 C18 P52643 /update {wt=javabin&version=2} {add=[10112 (1412962883173613568)]} 0 3
[junit4:junit4]   2> 6015 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6017 T437 C18 P52643 /update {wt=javabin&version=2} {add=[106 (1412962883172564992)]} 0 7
[junit4:junit4]   2> 6018 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[10111 (-1412962883180953600)]} 0 0
[junit4:junit4]   2> 6021 T421 C17 P46100 /update {wt=javabin&version=2} {add=[107 (1412962883182002176)]} 0 3
[junit4:junit4]   2> 6026 T447 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6026 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6027 T436 C18 P52643 /update {wt=javabin&version=2} {add=[107 (1412962883187245056)]} 0 4
[junit4:junit4]   2> 6029 T421 C17 P46100 /update {wt=javabin&version=2} {delete=[104 (-1412962883192487936)]} 0 1
[junit4:junit4]   2> 6031 T437 C18 P52643 /update {wt=javabin&version=2} {delete=[10111 (-1412962883187245057)]} 0 7
[junit4:junit4]   2> 6032 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6033 T436 C18 P52643 /update {wt=javabin&version=2} {delete=[104 (-1412962883194585088)]} 0 3
[junit4:junit4]   2> 6033 T421 C17 P46100 /update {wt=javabin&version=2} {add=[10113 (1412962883196682240)]} 0 1
[junit4:junit4]   2> 6047 T422 C17 P46100 /update {wt=javabin&version=2} {add=[108 (1412962883198779392)]} 0 12
[junit4:junit4]   2> 6055 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=3 
[junit4:junit4]   2> 6059 T437 C18 P52643 /update {wt=javabin&version=2} {add=[108 (1412962883214508032)]} 0 9
[junit4:junit4]   2> 6061 T422 C17 P46100 /update {wt=javabin&version=2} {delete=[105 (-1412962883226042368)]} 0 0
[junit4:junit4]   2> 6065 T448 C16 P34541 REQ /update {wt=javabin&distrib.from=http://127.0.0.1:52643/solr/collection1/&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6065 T437 C18 P52643 /update {

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

ocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3459,cumulative_deletesById=1737,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 24301 T409 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 24302 T409 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 25709 T442 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:39557
[junit4:junit4]   2> 25726 T581 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:39557
[junit4:junit4]   2> 25726 T581 oaz.ClientCnxn$SendThread.run WARNING Session 0x139bda401530007 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused
[junit4:junit4]   2> 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4:junit4]   2> 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:692)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1143)
[junit4:junit4]   2> 
[junit4:junit4]   2> 25809 T443 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 25809 T409 oaz.ZooKeeper.close Session: 0x139bda401530003 closed
[junit4:junit4]   2> 25810 T409 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 25862 T409 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 34541
[junit4:junit4]   2> 25863 T409 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1081719
[junit4:junit4]   2> 27096 T581 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:39557
[junit4:junit4]   2> 27197 T582 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 27197 T409 oaz.ZooKeeper.close Session: 0x139bda401530007 closed
[junit4:junit4]   2> 27198 T409 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 27283 T409 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=9E418DA25C22E4B1 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es -Dtests.timezone=America/Porto_Velho -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   27.3s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://localhost:34541/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([9E418DA25C22E4B1:1FA703BA2B7D848D]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:722)
[junit4:junit4]   2> 27321 T409 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene3x, sim=DefaultSimilarity, locale=es, timezone=America/Porto_Velho
[junit4:junit4]   2> NOTE: Linux 3.2.0-29-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=122148176,total=289406976
[junit4:junit4]   2> NOTE: All tests run in this JVM: [OutputWriterTest, TestDFRSimilarityFactory, TestLRUCache, TestHashPartitioner, TestPropInjectDefaults, PeerSyncTest, TestUtils, LeaderElectionTest, LegacyHTMLStripCharFilterTest, FileUtilsTest, SuggesterTSTTest, TestBinaryField, ZkNodePropsTest, TestDistributedSearch, TestCharFilters, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 27.36s, 1 test, 1 error <<< FAILURES!

[...truncated 633 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:29: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:146: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/module-build.xml:63: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1133: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:810: There were test failures: 229 suites, 926 tests, 1 error, 6 ignored (1 assumption)

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



Mime
View raw message