lucene-dev mailing list archives

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

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

Error Message:
Server at http://localhost:41496/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:41496/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([C214BA3409979D:81249AA24356F7A1]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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 7699 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 3 T1216 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1347519574174
[junit4:junit4]   2> 3 T1216 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T1217 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 5 T1217 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 5 T1217 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 104 T1216 oasc.ZkTestServer.run start zk server on port:55486
[junit4:junit4]   2> 104 T1216 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55486 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@12e9ce8
[junit4:junit4]   2> 105 T1222 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55486
[junit4:junit4]   2> 105 T1216 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T1222 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55486, initiating session
[junit4:junit4]   2> 106 T1218 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39562
[junit4:junit4]   2> 106 T1218 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39562
[junit4:junit4]   2> 106 T1220 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 204 T1220 oazs.NIOServerCnxn.finishSessionInit Established session 0x139be6cf0a00000 with negotiated timeout 10000 for client /127.0.0.1:39562
[junit4:junit4]   2> 204 T1222 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55486, sessionid = 0x139be6cf0a00000, negotiated timeout = 10000
[junit4:junit4]   2> 205 T1223 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12e9ce8 name:ZooKeeperConnection Watcher:127.0.0.1:55486 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 205 T1216 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 205 T1216 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 208 T1221 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139be6cf0a00000
[junit4:junit4]   2> 208 T1223 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 208 T1216 oaz.ZooKeeper.close Session: 0x139be6cf0a00000 closed
[junit4:junit4]   2> 209 T1218 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39562 which had sessionid 0x139be6cf0a00000
[junit4:junit4]   2> 209 T1216 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55486/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@8ed0c6
[junit4:junit4]   2> 210 T1224 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55486
[junit4:junit4]   2> 210 T1216 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 210 T1224 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55486, initiating session
[junit4:junit4]   2> 210 T1218 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39563
[junit4:junit4]   2> 210 T1218 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39563
[junit4:junit4]   2> 211 T1220 oazs.NIOServerCnxn.finishSessionInit Established session 0x139be6cf0a00001 with negotiated timeout 10000 for client /127.0.0.1:39563
[junit4:junit4]   2> 211 T1224 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55486, sessionid = 0x139be6cf0a00001, negotiated timeout = 10000
[junit4:junit4]   2> 211 T1225 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8ed0c6 name:ZooKeeperConnection Watcher:127.0.0.1:55486/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 212 T1216 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 212 T1216 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 216 T1216 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 218 T1216 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 219 T1216 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 221 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 222 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 225 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 226 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 229 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 230 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 234 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 234 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 238 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 239 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 241 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 242 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 244 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 244 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 247 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 247 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 250 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 250 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 252 T1216 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 253 T1216 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 255 T1221 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139be6cf0a00001
[junit4:junit4]   2> 255 T1218 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39563 which had sessionid 0x139be6cf0a00001
[junit4:junit4]   2> 255 T1216 oaz.ZooKeeper.close Session: 0x139be6cf0a00001 closed
[junit4:junit4]   2> 255 T1225 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 257 T1216 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 260 T1216 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:43284
[junit4:junit4]   2> 260 T1216 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 261 T1216 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 261 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 277 T1216 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 277 T1216 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 278 T1216 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 278 T1216 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 279 T1216 oasc.CoreContainer.<init> New CoreContainer 8551552
[junit4:junit4]   2> 279 T1216 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 280 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 302 T1216 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 312 T1216 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55486/solr
[junit4:junit4]   2> 312 T1216 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55486/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@af9a1a
[junit4:junit4]   2> 313 T1235 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55486
[junit4:junit4]   2> 313 T1216 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 314 T1218 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39564
[junit4:junit4]   2> 314 T1235 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55486, initiating session
[junit4:junit4]   2> 314 T1218 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39564
[junit4:junit4]   2> 316 T1220 oazs.NIOServerCnxn.finishSessionInit Established session 0x139be6cf0a00002 with negotiated timeout 8000 for client /127.0.0.1:39564
[junit4:junit4]   2> 316 T1235 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55486, sessionid = 0x139be6cf0a00002, negotiated timeout = 8000
[junit4:junit4]   2> 316 T1236 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@af9a1a name:ZooKeeperConnection Watcher:127.0.0.1:55486/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 317 T1216 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 318 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 318 T1216 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 320 T1216 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43284_solr
[junit4:junit4]   2> 321 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:43284_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:43284_solr
[junit4:junit4]   2> 321 T1216 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43284_solr
[junit4:junit4]   2> 324 T1216 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 325 T1216 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 325 T1216 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 326 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 326 T1216 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 327 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 327 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 327 T1216 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 328 T1216 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 328 T1216 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 333 T1216 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 336 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 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> 337 T1216 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 339 T1216 oasc.Overseer.start Overseer (id=88311042813198338-127.0.0.1:43284_solr-n_0000000000) starting
[junit4:junit4]   2> 340 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 340 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 341 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 341 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 342 T1238 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 342 T1216 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 344 T1216 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 345 T1237 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 348 T1216 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 348 T1216 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 348 T1216 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 349 T1216 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 350 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 350 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 350 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 375 T1216 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 420 T1216 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 421 T1216 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 428 T1216 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 752 T1216 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 758 T1216 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 761 T1216 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 771 T1216 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 774 T1216 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 777 T1216 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 778 T1216 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 779 T1216 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1347519574174/control/data/
[junit4:junit4]   2> 779 T1216 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@152f6aa
[junit4:junit4]   2> 786 T1216 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347519574174/control/data/index/
[junit4:junit4]   2> 786 T1216 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347519574174/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 786 T1216 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347519574174/control/data/index forceNew:false
[junit4:junit4]   2> 789 T1216 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347519574174/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 790 T1216 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 790 T1216 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 792 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 792 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 792 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 793 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 793 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 794 T1216 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 794 T1216 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 794 T1216 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 795 T1216 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 795 T1216 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 796 T1216 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 796 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 797 T1216 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 797 T1216 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 797 T1216 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 798 T1216 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 798 T1216 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 798 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 799 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 799 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 800 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 800 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 800 T1216 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 801 T1216 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 801 T1216 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 802 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 804 T1216 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 805 T1216 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 806 T1216 oass.SolrIndexSearcher.<init> Opening Searcher@1b79947 main
[junit4:junit4]   2> 807 T1216 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 808 T1216 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 808 T1216 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 811 T1216 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 831 T1239 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b79947 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 836 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 839 T1216 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 839 T1216 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:43284/solr shardId:control_shard
[junit4:junit4]   2> 840 T1216 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 845 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:delete cxid:0x5f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 846 T1216 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 847 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x61 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 848 T1216 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:43284/solr/collection1/
[junit4:junit4]   2> 848 T1237 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 848 T1216 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 849 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x67 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 853 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00002 type:create cxid:0x76 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 861 T1236 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 904 T1216 oasc.ZkController.register We are http://127.0.0.1:43284/solr/collection1/ and leader is http://127.0.0.1:43284/solr/collection1/
[junit4:junit4]   2> 905 T1216 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:43284/solr
[junit4:junit4]   2> 905 T1216 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 907 T1216 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 908 T1216 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 908 T1216 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 909 T1216 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 913 T1216 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 915 T1216 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:45404
[junit4:junit4]   2> 916 T1216 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 917 T1216 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 917 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 941 T1216 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 942 T1216 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 942 T1216 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 943 T1216 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 943 T1216 oasc.CoreContainer.<init> New CoreContainer 14061753
[junit4:junit4]   2> 944 T1216 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 944 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 958 T1216 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 965 T1216 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55486/solr
[junit4:junit4]   2> 965 T1216 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55486/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@6d5d84
[junit4:junit4]   2> 966 T1249 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55486
[junit4:junit4]   2> 966 T1216 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 967 T1249 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55486, initiating session
[junit4:junit4]   2> 967 T1218 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39565
[junit4:junit4]   2> 967 T1218 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39565
[junit4:junit4]   2> 968 T1220 oazs.NIOServerCnxn.finishSessionInit Established session 0x139be6cf0a00003 with negotiated timeout 8000 for client /127.0.0.1:39565
[junit4:junit4]   2> 968 T1249 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55486, sessionid = 0x139be6cf0a00003, negotiated timeout = 8000
[junit4:junit4]   2> 968 T1250 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d5d84 name:ZooKeeperConnection Watcher:127.0.0.1:55486/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 969 T1216 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 969 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 970 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 971 T1216 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45404_solr
[junit4:junit4]   2> 971 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:45404_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:45404_solr
[junit4:junit4]   2> 972 T1216 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:45404_solr
[junit4:junit4]   2> 974 T1236 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 974 T1216 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 975 T1216 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 975 T1216 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 975 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 976 T1216 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 976 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 976 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 976 T1216 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 977 T1216 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 977 T1216 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 982 T1216 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 985 T1216 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 985 T1216 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 986 T1216 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 986 T1216 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 987 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 988 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 988 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1011 T1216 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1050 T1216 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1051 T1216 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1056 T1216 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1367 T1237 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1370 T1236 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1370 T1250 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1375 T1216 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1381 T1216 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1384 T1216 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1394 T1216 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1398 T1216 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1402 T1216 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1404 T1216 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1405 T1216 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty1/
[junit4:junit4]   2> 1406 T1216 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@152f6aa
[junit4:junit4]   2> 1407 T1216 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty1/index/
[junit4:junit4]   2> 1407 T1216 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1408 T1216 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty1/index forceNew:false
[junit4:junit4]   2> 1411 T1216 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1412 T1216 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1413 T1216 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1415 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1416 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1416 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1417 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1418 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1418 T1216 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1419 T1216 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1419 T1216 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1420 T1216 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1421 T1216 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1421 T1216 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1422 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1422 T1216 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1423 T1216 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1423 T1216 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1423 T1216 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1424 T1216 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1424 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1425 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1425 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1426 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1426 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1427 T1216 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1427 T1216 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1428 T1216 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1429 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1430 T1216 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1431 T1216 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1433 T1216 oass.SolrIndexSearcher.<init> Opening Searcher@1c9d92f main
[junit4:junit4]   2> 1434 T1216 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1434 T1216 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1434 T1216 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1438 T1216 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1456 T1251 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c9d92f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1873 T1237 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1876 T1236 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1876 T1250 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1968 T1216 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1969 T1216 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:45404/solr shardId:shard1
[junit4:junit4]   2> 1969 T1216 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 1975 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00003 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> 1975 T1216 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 1976 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1977 T1216 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:45404/solr/collection1/
[junit4:junit4]   2> 1977 T1216 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 1980 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2379 T1237 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2386 T1236 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2386 T1250 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2432 T1216 oasc.ZkController.register We are http://127.0.0.1:45404/solr/collection1/ and leader is http://127.0.0.1:45404/solr/collection1/
[junit4:junit4]   2> 2433 T1216 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:45404/solr
[junit4:junit4]   2> 2434 T1216 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2435 T1216 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2436 T1216 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 2437 T1216 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2437 T1216 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2442 T1216 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2446 T1216 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:41496
[junit4:junit4]   2> 2446 T1216 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2447 T1216 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 2447 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2458 T1216 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2458 T1216 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2459 T1216 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 2459 T1216 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 2459 T1216 oasc.CoreContainer.<init> New CoreContainer 28768107
[junit4:junit4]   2> 2460 T1216 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2460 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2471 T1216 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2478 T1216 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55486/solr
[junit4:junit4]   2> 2479 T1216 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55486/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@8d55d1
[junit4:junit4]   2> 2479 T1261 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55486
[junit4:junit4]   2> 2479 T1216 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2480 T1218 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39566
[junit4:junit4]   2> 2480 T1261 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55486, initiating session
[junit4:junit4]   2> 2481 T1218 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39566
[junit4:junit4]   2> 2482 T1220 oazs.NIOServerCnxn.finishSessionInit Established session 0x139be6cf0a00004 with negotiated timeout 8000 for client /127.0.0.1:39566
[junit4:junit4]   2> 2482 T1261 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55486, sessionid = 0x139be6cf0a00004, negotiated timeout = 8000
[junit4:junit4]   2> 2482 T1262 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8d55d1 name:ZooKeeperConnection Watcher:127.0.0.1:55486/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2482 T1216 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2483 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2484 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2484 T1216 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41496_solr
[junit4:junit4]   2> 2485 T1221 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139be6cf0a00004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:41496_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:41496_solr
[junit4:junit4]   2> 2485 T1216 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41496_solr
[junit4:junit4]   2> 2486 T1236 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2486 T1250 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2487 T1216 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2487 T1216 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2488 T1216 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2488 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2488 T1216 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2488 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2489 T1216 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2489 T1216 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2489 T1216 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2490 T1216 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2493 T1216 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2497 T1216 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 2497 T1216 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2497 T1216 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2498 T1216 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2498 T1216 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 2499 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 2499 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2523 T1216 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2564 T1216 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2565 T1216 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2570 T1216 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2891 T1237 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2894 T1236 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2894 T1262 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2894 T1250 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2906 T1216 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2915 T1216 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2919 T1216 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2931 T1216 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2936 T1216 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2939 T1216 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2940 T1216 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2941 T1216 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty2/
[junit4:junit4]   2> 2941 T1216 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@152f6aa
[junit4:junit4]   2> 2942 T1216 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty2/index/
[junit4:junit4]   2> 2944 T1216 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2944 T1216 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty2/index forceNew:false
[junit4:junit4]   2> 2948 T1216 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2948 T1216 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2949 T1216 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2950 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2951 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2951 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2952 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2952 T1216 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2952 T1216 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2953 T1216 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2953 T1216 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2954 T1216 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2954 T1216 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2955 T1216 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2955 T1216 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2956 T1216 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2956 T1216 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2957 T1216 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2957 T1216 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2957 T1216 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2958 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2958 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2959 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2965 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2965 T1216 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2966 T1216 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2966 T1216 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2967 T1216 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2968 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2969 T1216 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2970 T1216 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 2973 T1216 oass.SolrIndexSearcher.<init> Opening Searcher@15d2749 main
[junit4:junit4]   2> 2973 T1216 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2974 T1216 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2974 T1216 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2978 T1216 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2998 T1263 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15d2749 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3398 T1237 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3401 T1236 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3401 T1250 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3401 T1262 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3504 T1216 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3505 T1216 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:41496/solr shardId:shard1
[junit4:junit4]   2> 3509 T1216 oasc.ZkController.register We are http://127.0.0.1:41496/solr/collection1/ and leader is http://127.0.0.1:45404/solr/collection1/
[junit4:junit4]   2> 3509 T1216 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:41496/solr
[junit4:junit4]   2> 3510 T1216 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 3510 T1216 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C76 name=collection1 org.apache.solr.core.SolrCore@1d9bf9a url=http://127.0.0.1:41496/solr/collection1 node=127.0.0.1:41496_solr
[junit4:junit4]   2> 3511 T1264 C76 P41496 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 3512 T1264 C76 P41496 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 3511 T1216 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3512 T1264 C76 P41496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3513 T1216 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3513 T1216 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3513 T1216 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3515 T1216 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55486/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1daee87
[junit4:junit4]   2> 3516 T1265 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55486
[junit4:junit4]   2> 3516 T1216 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3517 T1265 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55486, initiating session
[junit4:junit4]   2> 3517 T1218 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39568
[junit4:junit4]   2> 3517 T1218 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39568
[junit4:junit4]   2> 3518 T1220 oazs.NIOServerCnxn.finishSessionInit Established session 0x139be6cf0a00005 with negotiated timeout 10000 for client /127.0.0.1:39568
[junit4:junit4]   2> 3518 T1265 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55486, sessionid = 0x139be6cf0a00005, negotiated timeout = 10000
[junit4:junit4]   2> 3518 T1266 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1daee87 name:ZooKeeperConnection Watcher:127.0.0.1:55486/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3518 T1216 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3519 T1216 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3520 T1216 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:false
[junit4:junit4]   2> 3520 T1216 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3523 T1216 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C77 name=collection1 org.apache.solr.core.SolrCore@af3a18 url=http://127.0.0.1:43284/solr/collection1 node=127.0.0.1:43284_solr
[junit4:junit4]   2> 3530 T1229 C77 P43284 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347519574174/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3531 T1229 C77 P43284 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3532 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10001 (1412976688706355200)]} 0 4
[junit4:junit4]   2> 3532 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[1 (1412976688706355201)]} 0 4
[junit4:junit4]   2> 3533 T1268 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55486/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@890338
[junit4:junit4]   2> 3534 T1269 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55486
[junit4:junit4]   2> 3534 T1268 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3534 T1269 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55486, initiating session
[junit4:junit4]   2> 3534 T1218 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39571
[junit4:junit4]   2> 3534 T1218 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39571
[junit4:junit4]   2> 3535 T1220 oazs.NIOServerCnxn.finishSessionInit Established session 0x139be6cf0a00006 with negotiated timeout 10000 for client /127.0.0.1:39571
[junit4:junit4]   2> 3535 T1269 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55486, sessionid = 0x139be6cf0a00006, negotiated timeout = 10000
[junit4:junit4]   2> 3536 T1270 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@890338 name:ZooKeeperConnection Watcher:127.0.0.1:55486/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3536 T1268 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3536 T1268 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C78 name=collection1 org.apache.solr.core.SolrCore@77d2bd url=http://127.0.0.1:45404/solr/collection1 node=127.0.0.1:45404_solr
[junit4:junit4]   2> 3543 T1244 C78 P45404 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3544 T1244 C78 P45404 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3546 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[1 (1412976688718938112)]} 0 5
[junit4:junit4]   2> 3546 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10001 (1412976688718938113)]} 0 5
[junit4:junit4]   2> 3548 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10001 (-1412976688726278144)]} 0 0
[junit4:junit4]   2> 3548 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[1 (-1412976688726278145)]} 0 0
[junit4:junit4]   2> 3550 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[1 (-1412976688728375297)]} 0 0
[junit4:junit4]   2> 3550 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10001 (-1412976688728375296)]} 0 0
[junit4:junit4]   2> 3553 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10002 (1412976688731521024)]} 0 1
[junit4:junit4]   2> 3553 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[2 (1412976688731521025)]} 0 1
[junit4:junit4]   2> 3556 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[2 (1412976688733618177)]} 0 1
[junit4:junit4]   2> 3556 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10002 (1412976688733618176)]} 0 1
[junit4:junit4]   2> 3558 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[3 (1412976688736763904)]} 0 0
[junit4:junit4]   2> 3558 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10003 (1412976688736763905)]} 0 0
[junit4:junit4]   2> 3561 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10003 (1412976688738861056)]} 0 1
[junit4:junit4]   2> 3561 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[3 (1412976688738861057)]} 0 1
[junit4:junit4]   2> 3563 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10004 (1412976688742006784)]} 0 1
[junit4:junit4]   2> 3563 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[4 (1412976688742006785)]} 0 0
[junit4:junit4]   2> 3565 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10004 (1412976688744103936)]} 0 0
[junit4:junit4]   2> 3565 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[4 (1412976688744103937)]} 0 0
[junit4:junit4]   2> 3568 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10005 (1412976688746201088)]} 0 1
[junit4:junit4]   2> 3570 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10005 (1412976688749346816)]} 0 0
[junit4:junit4]   2> 3573 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10006 (1412976688751443968)]} 0 1
[junit4:junit4]   2> 3576 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10006 (1412976688754589696)]} 0 1
[junit4:junit4]   2> 3578 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10007 (1412976688757735424)]} 0 1
[junit4:junit4]   2> 3578 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[5 (1412976688746201089)]} 0 11
[junit4:junit4]   2> 3580 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10007 (1412976688759832576)]} 0 0
[junit4:junit4]   2> 3580 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[5 (1412976688759832577)]} 0 0
[junit4:junit4]   2> 3583 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10008 (1412976688761929728)]} 0 1
[junit4:junit4]   2> 3583 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[6 (1412976688761929729)]} 0 1
[junit4:junit4]   2> 3585 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10008 (1412976688765075456)]} 0 1
[junit4:junit4]   2> 3585 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[6 (1412976688765075457)]} 0 0
[junit4:junit4]   2> 3587 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10009 (1412976688767172608)]} 0 0
[junit4:junit4]   2> 3588 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[7 (1412976688767172609)]} 0 1
[junit4:junit4]   2> 3590 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10009 (1412976688769269760)]} 0 1
[junit4:junit4]   2> 3590 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[7 (1412976688770318336)]} 0 0
[junit4:junit4]   2> 3592 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10010 (1412976688772415488)]} 0 0
[junit4:junit4]   2> 3592 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[8 (1412976688772415489)]} 0 0
[junit4:junit4]   2> 3594 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10010 (1412976688774512640)]} 0 0
[junit4:junit4]   2> 3597 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10011 (1412976688777658368)]} 0 1
[junit4:junit4]   2> 3600 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10011 (1412976688779755520)]} 0 1
[junit4:junit4]   2> 3601 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[8 (1412976688774512641)]} 0 7
[junit4:junit4]   2> 3603 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[9 (1412976688783949824)]} 0 0
[junit4:junit4]   2> 3606 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[9 (1412976688787095552)]} 0 1
[junit4:junit4]   2> 3608 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10012 (1412976688782901248)]} 0 6
[junit4:junit4]   2> 3609 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10 (1412976688790241280)]} 0 1
[junit4:junit4]   2> 3610 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10012 (1412976688791289856)]} 0 0
[junit4:junit4]   2> 3612 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10013 (1412976688793387008)]} 0 0
[junit4:junit4]   2> 3615 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10013 (1412976688795484160)]} 0 1
[junit4:junit4]   2> 3617 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10014 (1412976688797581312)]} 0 1
[junit4:junit4]   2> 3619 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10014 (1412976688800727040)]} 0 0
[junit4:junit4]   2> 3620 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10 (1412976688792338432)]} 0 9
[junit4:junit4]   2> 3622 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10015 (1412976688802824192)]} 0 1
[junit4:junit4]   2> 3622 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[11 (1412976688803872768)]} 0 0
[junit4:junit4]   2> 3624 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[11 (1412976688805969920)]} 0 0
[junit4:junit4]   2> 3625 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10015 (1412976688804921344)]} 0 2
[junit4:junit4]   2> 3627 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[12 (1412976688808067072)]} 0 1
[junit4:junit4]   2> 3627 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10016 (1412976688809115648)]} 0 0
[junit4:junit4]   2> 3629 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10016 (1412976688811212801)]} 0 0
[junit4:junit4]   2> 3629 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[12 (1412976688811212800)]} 0 1
[junit4:junit4]   2> 3631 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10017 (1412976688813309952)]} 0 0
[junit4:junit4]   2> 3634 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10017 (1412976688815407104)]} 0 1
[junit4:junit4]   2> 3637 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10018 (1412976688818552832)]} 0 1
[junit4:junit4]   2> 3637 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[13 (1412976688813309953)]} 0 6
[junit4:junit4]   2> 3639 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10018 (1412976688821698560)]} 0 1
[junit4:junit4]   2> 3639 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[13 (1412976688821698561)]} 0 0
[junit4:junit4]   2> 3642 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10019 (1412976688823795712)]} 0 1
[junit4:junit4]   2> 3642 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[14 (1412976688823795713)]} 0 1
[junit4:junit4]   2> 3644 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10019 (1412976688826941440)]} 0 1
[junit4:junit4]   2> 3644 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[14 (1412976688826941441)]} 0 0
[junit4:junit4]   2> 3646 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10016 (-1412976688829038592)]} 0 0
[junit4:junit4]   2> 3647 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[15 (1412976688829038593)]} 0 1
[junit4:junit4]   2> 3649 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10016 (-1412976688832184320)]} 0 1
[junit4:junit4]   2> 3649 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[15 (1412976688832184321)]} 0 0
[junit4:junit4]   2> 3651 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10020 (1412976688834281472)]} 0 0
[junit4:junit4]   2> 3652 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[16 (1412976688834281473)]} 0 1
[junit4:junit4]   2> 3654 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[16 (1412976688836378625)]} 0 1
[junit4:junit4]   2> 3656 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[17 (1412976688839524352)]} 0 1
[junit4:junit4]   2> 3658 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[17 (1412976688841621504)]} 0 0
[junit4:junit4]   2> 3659 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10020 (1412976688836378624)]} 0 6
[junit4:junit4]   2> 3660 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[18 (1412976688843718656)]} 0 0
[junit4:junit4]   2> 3661 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10018 (-1412976688844767232)]} 0 0
[junit4:junit4]   2> 3663 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[18 (1412976688845815808)]} 0 1
[junit4:junit4]   2> 3663 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10018 (-1412976688846864384)]} 0 0
[junit4:junit4]   2> 3665 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[19 (1412976688848961536)]} 0 0
[junit4:junit4]   2> 3667 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[19 (1412976688851058688)]} 0 0
[junit4:junit4]   2> 3670 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[16 (-1412976688854204416)]} 0 1
[junit4:junit4]   2> 3671 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10021 (1412976688848961537)]} 0 6
[junit4:junit4]   2> 3672 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[16 (-1412976688856301568)]} 0 1
[junit4:junit4]   2> 3674 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10021 (1412976688857350144)]} 0 1
[junit4:junit4]   2> 3682 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[20 (1412976688857350144)]} 0 9
[junit4:junit4]   2> 3684 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10022 (1412976688868884480)]} 0 0
[junit4:junit4]   2> 3685 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[20 (1412976688868884480)]} 0 1
[junit4:junit4]   2> 3687 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[18 (-1412976688872030208)]} 0 1
[junit4:junit4]   2> 3687 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10022 (1412976688870981632)]} 0 1
[junit4:junit4]   2> 3689 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[18 (-1412976688874127360)]} 0 0
[junit4:junit4]   2> 3689 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10019 (-1412976688874127360)]} 0 0
[junit4:junit4]   2> 3691 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[21 (1412976688876224512)]} 0 1
[junit4:junit4]   2> 3691 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10019 (-1412976688876224512)]} 0 0
[junit4:junit4]   2> 3693 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[21 (1412976688878321664)]} 0 0
[junit4:junit4]   2> 3693 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10023 (1412976688878321664)]} 0 0
[junit4:junit4]   2> 3695 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10023 (1412976688880418816)]} 0 0
[junit4:junit4]   2> 3695 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[22 (1412976688880418816)]} 0 0
[junit4:junit4]   2> 3698 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10024 (1412976688882515968)]} 0 1
[junit4:junit4]   2> 3698 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[22 (1412976688882515968)]} 0 1
[junit4:junit4]   2> 3700 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[19 (-1412976688885661696)]} 0 1
[junit4:junit4]   2> 3702 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[19 (-1412976688887758848)]} 0 1
[junit4:junit4]   2> 3704 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[23 (1412976688889856000)]} 0 0
[junit4:junit4]   2> 3706 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10024 (1412976688884613120)]} 0 7
[junit4:junit4]   2> 3706 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[23 (1412976688891953152)]} 0 0
[junit4:junit4]   2> 3708 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10025 (1412976688894050304)]} 0 1
[junit4:junit4]   2> 3709 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[24 (1412976688894050305)]} 0 1
[junit4:junit4]   2> 3710 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10025 (1412976688896147456)]} 0 0
[junit4:junit4]   2> 3711 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[24 (1412976688897196032)]} 0 0
[junit4:junit4]   2> 3715 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[25 (1412976688899293184)]} 0 2
[junit4:junit4]   2> 3717 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[25 (1412976688903487488)]} 0 0
[junit4:junit4]   2> 3719 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10026 (1412976688898244608)]} 0 7
[junit4:junit4]   2> 3720 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[26 (1412976688905584640)]} 0 1
[junit4:junit4]   2> 3722 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10026 (1412976688907681792)]} 0 1
[junit4:junit4]   2> 3722 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[26 (1412976688908730368)]} 0 0
[junit4:junit4]   2> 3724 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10020 (-1412976688910827520)]} 0 1
[junit4:junit4]   2> 3724 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[20 (-1412976688910827521)]} 0 0
[junit4:junit4]   2> 3726 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10020 (-1412976688912924672)]} 0 0
[junit4:junit4]   2> 3726 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[20 (-1412976688912924673)]} 0 0
[junit4:junit4]   2> 3728 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10027 (1412976688915021824)]} 0 0
[junit4:junit4]   2> 3729 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[27 (1412976688915021825)]} 0 1
[junit4:junit4]   2> 3731 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10027 (1412976688917118976)]} 0 1
[junit4:junit4]   2> 3733 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10028 (1412976688920264704)]} 0 1
[junit4:junit4]   2> 3735 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10028 (1412976688922361856)]} 0 0
[junit4:junit4]   2> 3737 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[27 (1412976688917118977)]} 0 7
[junit4:junit4]   2> 3738 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10022 (-1412976688924459008)]} 0 1
[junit4:junit4]   2> 3739 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[28 (1412976688926556160)]} 0 0
[junit4:junit4]   2> 3740 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10022 (-1412976688927604736)]} 0 1
[junit4:junit4]   2> 3742 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[28 (1412976688928653312)]} 0 1
[junit4:junit4]   2> 3742 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10029 (1412976688929701888)]} 0 0
[junit4:junit4]   2> 3744 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[22 (-1412976688931799040)]} 0 0
[junit4:junit4]   2> 3745 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10029 (1412976688931799040)]} 0 1
[junit4:junit4]   2> 3746 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[22 (-1412976688933896192)]} 0 0
[junit4:junit4]   2> 3747 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10030 (1412976688933896192)]} 0 1
[junit4:junit4]   2> 3748 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[29 (1412976688935993344)]} 0 0
[junit4:junit4]   2> 3749 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10030 (1412976688937041920)]} 0 1
[junit4:junit4]   2> 3751 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[29 (1412976688938090496)]} 0 1
[junit4:junit4]   2> 3753 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[30 (1412976688941236224)]} 0 1
[junit4:junit4]   2> 3756 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[30 (1412976688943333376)]} 0 1
[junit4:junit4]   2> 3758 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[31 (1412976688946479104)]} 0 0
[junit4:junit4]   2> 3760 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10031 (1412976688939139072)]} 0 9
[junit4:junit4]   2> 3763 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10031 (1412976688950673408)]} 0 1
[junit4:junit4]   2> 3765 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10032 (1412976688953819136)]} 0 0
[junit4:junit4]   2> 3766 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[31 (1412976688948576256)]} 0 6
[junit4:junit4]   2> 3768 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10032 (1412976688956964864)]} 0 1
[junit4:junit4]   2> 3768 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[32 (1412976688956964864)]} 0 0
[junit4:junit4]   2> 3770 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10025 (-1412976688959062016)]} 0 0
[junit4:junit4]   2> 3771 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[32 (1412976688959062016)]} 0 1
[junit4:junit4]   2> 3772 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10025 (-1412976688961159168)]} 0 0
[junit4:junit4]   2> 3773 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[25 (-1412976688962207744)]} 0 1
[junit4:junit4]   2> 3774 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10033 (1412976688963256320)]} 0 0
[junit4:junit4]   2> 3775 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[25 (-1412976688963256320)]} 0 1
[junit4:junit4]   2> 3777 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10033 (1412976688965353472)]} 0 1
[junit4:junit4]   2> 3777 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[33 (1412976688966402048)]} 0 1
[junit4:junit4]   2> 3779 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10034 (1412976688967450624)]} 0 1
[junit4:junit4]   2> 3779 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[33 (1412976688968499200)]} 0 0
[junit4:junit4]   2> 3781 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10034 (1412976688970596352)]} 0 1
[junit4:junit4]   2> 3782 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[34 (1412976688971644928)]} 0 1
[junit4:junit4]   2> 3784 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10027 (-1412976688972693504)]} 0 1
[junit4:junit4]   2> 3784 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[34 (1412976688973742080)]} 0 0
[junit4:junit4]   2> 3786 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10027 (-1412976688975839232)]} 0 1
[junit4:junit4]   2> 3786 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[27 (-1412976688975839232)]} 0 0
[junit4:junit4]   2> 3788 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[27 (-1412976688977936384)]} 0 0
[junit4:junit4]   2> 3788 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10035 (1412976688977936384)]} 0 0
[junit4:junit4]   2> 3791 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10035 (1412976688980033536)]} 0 1
[junit4:junit4]   2> 3794 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10036 (1412976688983179264)]} 0 2
[junit4:junit4]   2> 3796 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10036 (1412976688986324992)]} 0 0
[junit4:junit4]   2> 3799 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10037 (1412976688988422144)]} 0 1
[junit4:junit4]   2> 3799 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[35 (1412976688980033536)]} 0 9
[junit4:junit4]   2> 3801 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10037 (1412976688991567872)]} 0 0
[junit4:junit4]   2> 3802 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[35 (1412976688992616448)]} 0 1
[junit4:junit4]   2> 3804 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10038 (1412976688993665024)]} 0 1
[junit4:junit4]   2> 3804 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[36 (1412976688994713600)]} 0 0
[junit4:junit4]   2> 3806 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10038 (1412976688996810752)]} 0 0
[junit4:junit4]   2> 3808 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10039 (1412976688998907904)]} 0 0
[junit4:junit4]   2> 3811 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10039 (1412976689001005056)]} 0 1
[junit4:junit4]   2> 3815 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[36 (1412976688996810753)]} 0 9
[junit4:junit4]   2> 3815 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10028 (-1412976689006247936)]} 0 0
[junit4:junit4]   2> 3818 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10028 (-1412976689009393664)]} 0 1
[junit4:junit4]   2> 3818 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[37 (1412976689009393664)]} 0 1
[junit4:junit4]   2> 3821 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10040 (1412976689011490816)]} 0 1
[junit4:junit4]   2> 3822 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[37 (1412976689011490816)]} 0 2
[junit4:junit4]   2> 3823 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10040 (1412976689014636544)]} 0 0
[junit4:junit4]   2> 3824 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[38 (1412976689015685120)]} 0 0
[junit4:junit4]   2> 3826 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10041 (1412976689017782272)]} 0 1
[junit4:junit4]   2> 3826 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[38 (1412976689017782272)]} 0 0
[junit4:junit4]   2> 3829 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10041 (1412976689019879424)]} 0 1
[junit4:junit4]   2> 3831 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10029 (-1412976689023025152)]} 0 0
[junit4:junit4]   2> 3834 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10029 (-1412976689025122304)]} 0 1
[junit4:junit4]   2> 3837 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10042 (1412976689028268032)]} 0 1
[junit4:junit4]   2> 3840 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10042 (1412976689032462336)]} 0 1
[junit4:junit4]   2> 3843 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10031 (-1412976689034559488)]} 0 1
[junit4:junit4]   2> 3845 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10031 (-1412976689037705216)]} 0 0
[junit4:junit4]   2> 3847 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10043 (1412976689039802368)]} 0 0
[junit4:junit4]   2> 3850 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10043 (1412976689042948096)]} 0 1
[junit4:junit4]   2> 3852 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10033 (-1412976689045045248)]} 0 0
[junit4:junit4]   2> 3854 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10033 (-1412976689047142400)]} 0 0
[junit4:junit4]   2> 3857 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10044 (1412976689049239552)]} 0 1
[junit4:junit4]   2> 3859 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10044 (1412976689052385280)]} 0 0
[junit4:junit4]   2> 3861 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10034 (-1412976689054482432)]} 0 0
[junit4:junit4]   2> 3863 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10034 (-1412976689056579584)]} 0 0
[junit4:junit4]   2> 3866 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10045 (1412976689058676736)]} 0 1
[junit4:junit4]   2> 3868 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10045 (1412976689061822464)]} 0 0
[junit4:junit4]   2> 3871 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10035 (-1412976689064968192)]} 0 0
[junit4:junit4]   2> 3873 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10035 (-1412976689067065344)]} 0 0
[junit4:junit4]   2> 3873 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[39 (1412976689019879424)]} 0 45
[junit4:junit4]   2> 3876 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[39 (1412976689069162496)]} 0 1
[junit4:junit4]   2> 3876 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10046 (1412976689069162496)]} 0 1
[junit4:junit4]   2> 3878 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[28 (-1412976689072308224)]} 0 0
[junit4:junit4]   2> 3879 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10046 (1412976689072308224)]} 0 1
[junit4:junit4]   2> 3880 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[28 (-1412976689074405376)]} 0 0
[junit4:junit4]   2> 3881 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10047 (1412976689074405376)]} 0 1
[junit4:junit4]   2> 3883 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10047 (1412976689077551104)]} 0 0
[junit4:junit4]   2> 3883 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[40 (1412976689077551104)]} 0 1
[junit4:junit4]   2> 3885 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10036 (-1412976689079648256)]} 0 0
[junit4:junit4]   2> 3886 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[40 (1412976689079648256)]} 0 1
[junit4:junit4]   2> 3887 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10036 (-1412976689081745408)]} 0 0
[junit4:junit4]   2> 3888 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[41 (1412976689082793984)]} 0 0
[junit4:junit4]   2> 3890 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10048 (1412976689083842560)]} 0 1
[junit4:junit4]   2> 3890 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[41 (1412976689084891136)]} 0 0
[junit4:junit4]   2> 3892 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[29 (-1412976689086988288)]} 0 0
[junit4:junit4]   2> 3894 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[29 (-1412976689089085440)]} 0 0
[junit4:junit4]   2> 3904 T1237 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3909 T1266 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3909 T1262 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3909 T1270 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3909 T1250 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3909 T1236 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3925 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[42 (1412976689092231168)]} 0 28
[junit4:junit4]   2> 3946 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10048 (1412976689086988288)]} 0 54
[junit4:junit4]   2> 3950 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10049 (1412976689146757120)]} 0 2
[junit4:junit4]   2>  C76_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:41496_solr, base_url=http://127.0.0.1:41496/solr}
[junit4:junit4]   2> 3954 T1254 C76 P41496 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1347519574174/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3955 T1254 C76 P41496 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3956 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 3957 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[42 (1412976689124737024)]} 0 30
[junit4:junit4]   2> 3959 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[31 (-1412976689157242880)]} 0 0
[junit4:junit4]   2> 3961 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3962 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10049 (1412976689150951424)]} 0 9
[junit4:junit4]   2> 3963 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3963 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[31 (-1412976689159340032)]} 0 2
[junit4:junit4]   2> 3966 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[43 (1412976689164582912)]} 0 1
[junit4:junit4]   2> 3968 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10050 (1412976689165631488)]} 0 1
[junit4:junit4]   2> 3972 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3973 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[43 (1412976689167728640)]} 0 5
[junit4:junit4]   2> 3973 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3974 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10050 (1412976689168777216)]} 0 4
[junit4:junit4]   2> 3976 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[33 (-1412976689174020096)]} 0 1
[junit4:junit4]   2> 3977 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10051 (1412976689175068672)]} 0 1
[junit4:junit4]   2> 3980 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3981 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[33 (-1412976689177165824)]} 0 3
[junit4:junit4]   2> 3982 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3982 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10051 (1412976689178214400)]} 0 3
[junit4:junit4]   2> 3983 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[44 (1412976689182408704)]} 0 0
[junit4:junit4]   2> 3985 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10052 (1412976689183457280)]} 0 1
[junit4:junit4]   2> 3988 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3989 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[44 (1412976689185554432)]} 0 3
[junit4:junit4]   2> 3990 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3990 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10052 (1412976689186603008)]} 0 3
[junit4:junit4]   2> 3991 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[34 (-1412976689190797312)]} 0 0
[junit4:junit4]   2> 3992 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10040 (-1412976689191845888)]} 0 0
[junit4:junit4]   2> 3995 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3995 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[34 (-1412976689192894464)]} 0 2
[junit4:junit4]   2> 3996 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 3997 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10040 (-1412976689193943040)]} 0 3
[junit4:junit4]   2> 3998 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[45 (1412976689197088768)]} 0 1
[junit4:junit4]   2> 4001 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10053 (1412976689200234496)]} 0 1
[junit4:junit4]   2> 4003 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4004 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[45 (1412976689200234496)]} 0 4
[junit4:junit4]   2> 4005 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4005 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10053 (1412976689203380224)]} 0 2
[junit4:junit4]   2> 4006 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[35 (-1412976689206525952)]} 0 1
[junit4:junit4]   2> 4007 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10044 (-1412976689207574528)]} 0 0
[junit4:junit4]   2> 4009 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4010 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[35 (-1412976689208623104)]} 0 2
[junit4:junit4]   2> 4011 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4012 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10044 (-1412976689210720256)]} 0 3
[junit4:junit4]   2> 4012 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[46 (1412976689212817408)]} 0 0
[junit4:junit4]   2> 4022 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10054 (1412976689214914560)]} 0 8
[junit4:junit4]   2> 4025 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 4026 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[46 (1412976689214914560)]} 0 12
[junit4:junit4]   2> 4027 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4028 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10054 (1412976689225400320)]} 0 4
[junit4:junit4]   2> 4029 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[47 (1412976689229594624)]} 0 1
[junit4:junit4]   2> 4030 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10046 (-1412976689231691776)]} 0 0
[junit4:junit4]   2> 4033 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4033 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4034 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10046 (-1412976689233788928)]} 0 3
[junit4:junit4]   2> 4034 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[47 (1412976689232740352)]} 0 3
[junit4:junit4]   2> 4036 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[36 (-1412976689237983233)]} 0 0
[junit4:junit4]   2> 4036 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10055 (1412976689237983232)]} 0 0
[junit4:junit4]   2> 4040 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4041 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[36 (-1412976689240080384)]} 0 3
[junit4:junit4]   2> 4041 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4041 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10055 (1412976689240080385)]} 0 3
[junit4:junit4]   2> 4043 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[48 (1412976689245323264)]} 0 1
[junit4:junit4]   2> 4044 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10048 (-1412976689245323265)]} 0 1
[junit4:junit4]   2> 4047 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4047 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4048 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10048 (-1412976689247420417)]} 0 3
[junit4:junit4]   2> 4048 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[48 (1412976689247420416)]} 0 3
[junit4:junit4]   2> 4050 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10056 (1412976689251614720)]} 0 1
[junit4:junit4]   2> 4050 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[49 (1412976689252663296)]} 0 1
[junit4:junit4]   2> 4054 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4054 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4055 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[49 (1412976689254760449)]} 0 3
[junit4:junit4]   2> 4055 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10056 (1412976689254760448)]} 0 3
[junit4:junit4]   2> 4057 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10051 (-1412976689260003329)]} 0 0
[junit4:junit4]   2> 4057 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[50 (1412976689260003328)]} 0 0
[junit4:junit4]   2> 4061 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4061 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4061 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10051 (-1412976689262100480)]} 0 2
[junit4:junit4]   2> 4062 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[50 (1412976689262100481)]} 0 3
[junit4:junit4]   2> 4064 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10057 (1412976689266294784)]} 0 1
[junit4:junit4]   2> 4064 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[51 (1412976689266294785)]} 0 1
[junit4:junit4]   2> 4068 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4068 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4068 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10057 (1412976689269440512)]} 0 2
[junit4:junit4]   2> 4069 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[51 (1412976689269440513)]} 0 2
[junit4:junit4]   2> 4071 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10058 (1412976689273634816)]} 0 1
[junit4:junit4]   2> 4076 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[52 (1412976689273634817)]} 0 6
[junit4:junit4]   2> 4086 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4086 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[52 (1412976689282023424)]} 0 8
[junit4:junit4]   2> 4089 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[40 (-1412976689292509184)]} 0 1
[junit4:junit4]   2> 4089 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4089 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10058 (1412976689276780544)]} 0 16
[junit4:junit4]   2> 4092 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4092 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10059 (1412976689295654912)]} 0 1
[junit4:junit4]   2> 4093 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[40 (-1412976689295654912)]} 0 3
[junit4:junit4]   2> 4095 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[53 (1412976689299849216)]} 0 1
[junit4:junit4]   2> 4098 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4098 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10059 (1412976689298800640)]} 0 4
[junit4:junit4]   2> 4100 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4100 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[53 (1412976689301946368)]} 0 3
[junit4:junit4]   2> 4100 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10060 (1412976689305092096)]} 0 0
[junit4:junit4]   2> 4102 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[44 (-1412976689307189248)]} 0 0
[junit4:junit4]   2> 4105 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4105 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10060 (1412976689307189248)]} 0 3
[junit4:junit4]   2> 4106 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4106 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[44 (-1412976689309286400)]} 0 2
[junit4:junit4]   2> 4107 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10054 (-1412976689312432128)]} 0 0
[junit4:junit4]   2> 4109 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[54 (1412976689313480704)]} 0 1
[junit4:junit4]   2> 4111 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4111 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10054 (-1412976689314529280)]} 0 2
[junit4:junit4]   2> 4114 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10061 (1412976689318723584)]} 0 1
[junit4:junit4]   2> 4114 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4114 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[54 (1412976689316626432)]} 0 3
[junit4:junit4]   2> 4116 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[46 (-1412976689321869312)]} 0 0
[junit4:junit4]   2> 4118 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4119 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10061 (1412976689321869312)]} 0 4
[junit4:junit4]   2> 4120 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4120 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[46 (-1412976689323966464)]} 0 2
[junit4:junit4]   2> 4121 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10062 (1412976689327112192)]} 0 1
[junit4:junit4]   2> 4122 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[55 (1412976689328160768)]} 0 0
[junit4:junit4]   2> 4125 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4126 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10062 (1412976689329209344)]} 0 3
[junit4:junit4]   2> 4127 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4127 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[55 (1412976689330257920)]} 0 3
[junit4:junit4]   2> 4128 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10063 (1412976689333403648)]} 0 1
[junit4:junit4]   2> 4129 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[48 (-1412976689335500800)]} 0 0
[junit4:junit4]   2> 4132 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4133 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10063 (1412976689336549376)]} 0 3
[junit4:junit4]   2> 4133 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4134 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[48 (-1412976689338646528)]} 0 3
[junit4:junit4]   2> 4135 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10064 (1412976689341792256)]} 0 0
[junit4:junit4]   2> 4143 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[56 (1412976689342840832)]} 0 8
[junit4:junit4]   2> 4147 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 4148 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10064 (1412976689343889408)]} 0 11
[junit4:junit4]   2> 4149 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4150 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[56 (1412976689352278016)]} 0 5
[junit4:junit4]   2> 4151 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10065 (1412976689357520896)]} 0 1
[junit4:junit4]   2> 4152 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[51 (-1412976689359618048)]} 0 0
[junit4:junit4]   2> 4156 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4156 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4157 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10065 (1412976689360666624)]} 0 4
[junit4:junit4]   2> 4157 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[51 (-1412976689361715200)]} 0 3
[junit4:junit4]   2> 4159 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10057 (-1412976689366958080)]} 0 0
[junit4:junit4]   2> 4160 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[57 (1412976689366958081)]} 0 1
[junit4:junit4]   2> 4163 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4163 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10057 (-1412976689369055232)]} 0 2
[junit4:junit4]   2> 4164 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4165 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[57 (1412976689370103808)]} 0 3
[junit4:junit4]   2> 4166 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10066 (1412976689373249536)]} 0 1
[junit4:junit4]   2> 4167 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[58 (1412976689375346688)]} 0 0
[junit4:junit4]   2> 4170 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4171 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10066 (1412976689376395264)]} 0 3
[junit4:junit4]   2> 4172 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4172 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[58 (1412976689377443840)]} 0 3
[junit4:junit4]   2> 4173 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10067 (1412976689381638144)]} 0 0
[junit4:junit4]   2> 4174 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[59 (1412976689382686720)]} 0 0
[junit4:junit4]   2> 4177 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4178 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10067 (1412976689383735296)]} 0 3
[junit4:junit4]   2> 4178 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4179 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[59 (1412976689384783872)]} 0 3
[junit4:junit4]   2> 4180 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10068 (1412976689388978176)]} 0 0
[junit4:junit4]   2> 4181 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[60 (1412976689390026752)]} 0 0
[junit4:junit4]   2> 4191 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4192 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[60 (1412976689392123904)]} 0 9
[junit4:junit4]   2> 4194 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[54 (-1412976689403658240)]} 0 0
[junit4:junit4]   2> 4196 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4197 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10068 (1412976689391075328)]} 0 15
[junit4:junit4]   2> 4198 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4199 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10058 (-1412976689408901120)]} 0 1
[junit4:junit4]   2> 4199 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[54 (-1412976689406803968)]} 0 3
[junit4:junit4]   2> 4202 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[61 (1412976689410998272)]} 0 1
[junit4:junit4]   2> 4202 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4203 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10058 (-1412976689410998272)]} 0 2
[junit4:junit4]   2> 4211 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10069 (1412976689415192576)]} 0 6
[junit4:junit4]   2> 4213 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4214 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[61 (1412976689414144000)]} 0 10
[junit4:junit4]   2> 4217 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[62 (1412976689426726912)]} 0 1
[junit4:junit4]   2> 4220 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4220 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10069 (1412976689423581184)]} 0 7
[junit4:junit4]   2> 4223 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10070 (1412976689433018368)]} 0 1
[junit4:junit4]   2> 4228 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4228 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4228 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[62 (1412976689430921216)]} 0 9
[junit4:junit4]   2> 4229 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10070 (1412976689436164096)]} 0 4
[junit4:junit4]   2> 4231 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10061 (-1412976689442455553)]} 0 0
[junit4:junit4]   2> 4231 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[63 (1412976689442455552)]} 0 1
[junit4:junit4]   2> 4235 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4236 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10061 (-1412976689444552704)]} 0 3
[junit4:junit4]   2> 4237 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4237 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[63 (1412976689444552705)]} 0 4
[junit4:junit4]   2> 4238 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10071 (1412976689449795584)]} 0 0
[junit4:junit4]   2> 4239 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[64 (1412976689450844160)]} 0 0
[junit4:junit4]   2> 4242 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4243 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10071 (1412976689451892736)]} 0 3
[junit4:junit4]   2> 4244 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4244 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[64 (1412976689452941312)]} 0 3
[junit4:junit4]   2> 4245 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10072 (1412976689457135616)]} 0 0
[junit4:junit4]   2> 4247 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[65 (1412976689458184192)]} 0 1
[junit4:junit4]   2> 4249 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4250 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10072 (1412976689459232768)]} 0 3
[junit4:junit4]   2> 4251 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4251 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[65 (1412976689461329920)]} 0 2
[junit4:junit4]   2> 4252 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10062 (-1412976689464475648)]} 0 0
[junit4:junit4]   2> 4253 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[57 (-1412976689465524224)]} 0 0
[junit4:junit4]   2> 4256 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4256 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10062 (-1412976689466572800)]} 0 2
[junit4:junit4]   2> 4257 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4257 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[57 (-1412976689467621376)]} 0 2
[junit4:junit4]   2> 4258 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10073 (1412976689470767104)]} 0 0
[junit4:junit4]   2> 4260 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[66 (1412976689471815680)]} 0 1
[junit4:junit4]   2> 4263 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4263 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10073 (1412976689472864256)]} 0 3
[junit4:junit4]   2> 4265 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10063 (-1412976689478107136)]} 0 0
[junit4:junit4]   2> 4269 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4269 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4269 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[66 (1412976689473912832)]} 0 8
[junit4:junit4]   2> 4269 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10063 (-1412976689480204288)]} 0 2
[junit4:junit4]   2> 4272 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10074 (1412976689484398593)]} 0 1
[junit4:junit4]   2> 4277 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[67 (1412976689484398592)]} 0 6
[junit4:junit4]   2> 4278 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4278 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10074 (1412976689487544320)]} 0 4
[junit4:junit4]   2> 4280 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10064 (-1412976689493835776)]} 0 0
[junit4:junit4]   2> 4282 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4282 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[67 (1412976689492787200)]} 0 3
[junit4:junit4]   2> 4284 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4284 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10064 (-1412976689495932928)]} 0 2
[junit4:junit4]   2> 4284 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[68 (1412976689498030080)]} 0 0
[junit4:junit4]   2> 4287 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10075 (1412976689500127232)]} 0 1
[junit4:junit4]   2> 4289 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4289 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[68 (1412976689500127232)]} 0 3
[junit4:junit4]   2> 4291 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4291 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10075 (1412976689503272960)]} 0 2
[junit4:junit4]   2> 4291 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[58 (-1412976689505370112)]} 0 0
[junit4:junit4]   2> 4293 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10065 (-1412976689507467264)]} 0 0
[junit4:junit4]   2> 4295 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4296 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[58 (-1412976689507467264)]} 0 3
[junit4:junit4]   2> 4297 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4297 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10065 (-1412976689509564416)]} 0 2
[junit4:junit4]   2> 4298 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[69 (1412976689511661568)]} 0 1
[junit4:junit4]   2> 4299 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10076 (1412976689513758720)]} 0 0
[junit4:junit4]   2> 4302 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4302 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[69 (1412976689514807296)]} 0 2
[junit4:junit4]   2> 4303 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4304 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10076 (1412976689515855872)]} 0 3
[junit4:junit4]   2> 4305 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[70 (1412976689519001600)]} 0 1
[junit4:junit4]   2> 4306 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10077 (1412976689521098752)]} 0 0
[junit4:junit4]   2> 4309 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4309 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[70 (1412976689521098752)]} 0 3
[junit4:junit4]   2> 4310 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4310 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10077 (1412976689523195904)]} 0 2
[junit4:junit4]   2> 4311 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[61 (-1412976689526341632)]} 0 0
[junit4:junit4]   2> 4312 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10066 (-1412976689527390208)]} 0 0
[junit4:junit4]   2> 4315 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4315 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[61 (-1412976689528438784)]} 0 2
[junit4:junit4]   2> 4316 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4316 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10066 (-1412976689529487360)]} 0 2
[junit4:junit4]   2> 4317 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[71 (1412976689532633088)]} 0 0
[junit4:junit4]   2> 4318 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10078 (1412976689533681664)]} 0 0
[junit4:junit4]   2> 4322 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4322 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[71 (1412976689534730240)]} 0 3
[junit4:junit4]   2> 4333 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4334 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10078 (1412976689535778816)]} 0 14
[junit4:junit4]   2> 4337 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10079 (1412976689552556032)]} 0 2
[junit4:junit4]   2> 4342 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4343 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10079 (1412976689555701760)]} 0 5
[junit4:junit4]   2> 4345 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10067 (-1412976689561993216)]} 0 1
[junit4:junit4]   2> 4348 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4349 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10067 (-1412976689564090368)]} 0 2
[junit4:junit4]   2> 4351 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10080 (1412976689568284672)]} 0 0
[junit4:junit4]   2> 4352 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[72 (1412976689539973120)]} 0 28
[junit4:junit4]   2> 4355 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4356 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10080 (1412976689570381824)]} 0 3
[junit4:junit4]   2> 4359 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10068 (-1412976689575624704)]} 0 1
[junit4:junit4]   2> 4363 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4363 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10068 (-1412976689578770432)]} 0 2
[junit4:junit4]   2> 4366 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10081 (1412976689584013312)]} 0 1
[junit4:junit4]   2> 4371 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4372 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10081 (1412976689587159040)]} 0 4
[junit4:junit4]   2> 4375 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10082 (1412976689592401920)]} 0 1
[junit4:junit4]   2> 4380 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4381 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10082 (1412976689595547648)]} 0 4
[junit4:junit4]   2> 4383 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10071 (-1412976689601839104)]} 0 0
[junit4:junit4]   2> 4387 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4387 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10071 (-1412976689603936256)]} 0 2
[junit4:junit4]   2> 4389 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4390 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[72 (1412976689571430400)]} 0 36
[junit4:junit4]   2> 4390 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10083 (1412976689608130560)]} 0 1
[junit4:junit4]   2> 4392 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[62 (-1412976689611276288)]} 0 1
[junit4:junit4]   2> 4401 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4401 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4401 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[62 (-1412976689618616320)]} 0 2
[junit4:junit4]   2> 4401 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10083 (1412976689611276288)]} 0 9
[junit4:junit4]   2> 4403 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10072 (-1412976689622810625)]} 0 0
[junit4:junit4]   2> 4404 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[73 (1412976689622810624)]} 0 1
[junit4:junit4]   2> 4407 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4407 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10072 (-1412976689624907776)]} 0 2
[junit4:junit4]   2> 4408 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4408 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[73 (1412976689624907777)]} 0 3
[junit4:junit4]   2> 4409 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10084 (1412976689629102080)]} 0 0
[junit4:junit4]   2> 4410 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[63 (-1412976689630150656)]} 0 0
[junit4:junit4]   2> 4414 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4414 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4414 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[63 (-1412976689632247808)]} 0 2
[junit4:junit4]   2> 4414 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10084 (1412976689631199232)]} 0 3
[junit4:junit4]   2> 4416 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10074 (-1412976689636442112)]} 0 0
[junit4:junit4]   2> 4417 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[74 (1412976689636442113)]} 0 1
[junit4:junit4]   2> 4420 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4421 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10074 (-1412976689639587840)]} 0 2
[junit4:junit4]   2> 4421 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4422 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[74 (1412976689639587841)]} 0 3
[junit4:junit4]   2> 4423 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10085 (1412976689643782144)]} 0 0
[junit4:junit4]   2> 4423 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[64 (-1412976689643782145)]} 0 0
[junit4:junit4]   2> 4427 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4427 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[64 (-1412976689645879297)]} 0 2
[junit4:junit4]   2> 4433 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4433 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10085 (1412976689645879296)]} 0 8
[junit4:junit4]   2> 4436 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10077 (-1412976689656365056)]} 0 0
[junit4:junit4]   2> 4436 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[75 (1412976689650073600)]} 0 7
[junit4:junit4]   2> 4439 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4440 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10077 (-1412976689658462208)]} 0 3
[junit4:junit4]   2> 4441 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4442 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[75 (1412976689659510784)]} 0 4
[junit4:junit4]   2> 4442 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10086 (1412976689662656512)]} 0 1
[junit4:junit4]   2> 4444 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[65 (-1412976689665802240)]} 0 0
[junit4:junit4]   2> 4447 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4447 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10086 (1412976689665802240)]} 0 3
[junit4:junit4]   2> 4447 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4448 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[65 (-1412976689667899392)]} 0 3
[junit4:junit4]   2> 4450 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10087 (1412976689671045120)]} 0 1
[junit4:junit4]   2> 4450 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[76 (1412976689672093696)]} 0 0
[junit4:junit4]   2> 4454 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4454 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4455 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[76 (1412976689674190849)]} 0 3
[junit4:junit4]   2> 4455 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10087 (1412976689674190848)]} 0 3
[junit4:junit4]   2> 4457 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10083 (-1412976689679433729)]} 0 0
[junit4:junit4]   2> 4457 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[77 (1412976689679433728)]} 0 0
[junit4:junit4]   2> 4461 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4462 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10083 (-1412976689681530880)]} 0 3
[junit4:junit4]   2> 4464 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10088 (1412976689686773760)]} 0 0
[junit4:junit4]   2> 4467 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4467 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[77 (1412976689681530881)]} 0 8
[junit4:junit4]   2> 4469 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4469 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10088 (1412976689689919488)]} 0 3
[junit4:junit4]   2> 4469 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[66 (-1412976689692016640)]} 0 0
[junit4:junit4]   2> 4471 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10084 (-1412976689694113792)]} 0 0
[junit4:junit4]   2> 4473 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4474 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[66 (-1412976689695162368)]} 0 3
[junit4:junit4]   2> 4475 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4476 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10084 (-1412976689696210944)]} 0 3
[junit4:junit4]   2> 4476 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[78 (1412976689698308096)]} 0 1
[junit4:junit4]   2> 4478 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10089 (1412976689700405248)]} 0 1
[junit4:junit4]   2> 4480 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4481 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[78 (1412976689701453824)]} 0 3
[junit4:junit4]   2> 4482 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4482 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10089 (1412976689703550976)]} 0 2
[junit4:junit4]   2> 4483 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[79 (1412976689706696704)]} 0 0
[junit4:junit4]   2> 4484 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10087 (-1412976689707745280)]} 0 0
[junit4:junit4]   2> 4487 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4488 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[79 (1412976689708793856)]} 0 3
[junit4:junit4]   2> 4488 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4488 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10087 (-1412976689709842432)]} 0 2
[junit4:junit4]   2> 4490 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[67 (-1412976689714036736)]} 0 0
[junit4:junit4]   2> 4491 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10090 (1412976689714036737)]} 0 1
[junit4:junit4]   2> 4494 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4494 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[67 (-1412976689716133888)]} 0 2
[junit4:junit4]   2> 4502 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[80 (1412976689720328192)]} 0 6
[junit4:junit4]   2> 4507 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4508 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[80 (1412976689728716800)]} 0 4
[junit4:junit4]   2> 4510 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[68 (-1412976689735008256)]} 0 1
[junit4:junit4]   2> 4513 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4514 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[68 (-1412976689737105408)]} 0 2
[junit4:junit4]   2> 4517 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[81 (1412976689741299712)]} 0 1
[junit4:junit4]   2> 4521 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4522 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[81 (1412976689744445440)]} 0 3
[junit4:junit4]   2> 4523 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=29 
[junit4:junit4]   2> 4524 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10090 (1412976689716133889)]} 0 32
[junit4:junit4]   2> 4524 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[82 (1412976689749688320)]} 0 0
[junit4:junit4]   2> 4526 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10091 (1412976689751785472)]} 0 0
[junit4:junit4]   2> 4529 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4529 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[82 (1412976689751785472)]} 0 3
[junit4:junit4]   2> 4531 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4531 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10091 (1412976689753882624)]} 0 3
[junit4:junit4]   2> 4531 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[71 (-1412976689757028352)]} 0 0
[junit4:junit4]   2> 4534 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10092 (1412976689759125504)]} 0 1
[junit4:junit4]   2> 4535 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4535 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[71 (-1412976689759125504)]} 0 2
[junit4:junit4]   2> 4538 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[83 (1412976689764368384)]} 0 1
[junit4:junit4]   2> 4544 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4545 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4545 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[83 (1412976689766465536)]} 0 5
[junit4:junit4]   2> 4545 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10092 (1412976689762271232)]} 0 9
[junit4:junit4]   2> 4547 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[72 (-1412976689773805568)]} 0 0
[junit4:junit4]   2> 4548 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10093 (1412976689773805569)]} 0 1
[junit4:junit4]   2> 4551 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4551 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[72 (-1412976689775902720)]} 0 2
[junit4:junit4]   2> 4552 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4552 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10093 (1412976689776951296)]} 0 2
[junit4:junit4]   2> 4554 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[84 (1412976689780097024)]} 0 1
[junit4:junit4]   2> 4554 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10088 (-1412976689781145600)]} 0 0
[junit4:junit4]   2> 4558 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4558 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4558 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10088 (-1412976689783242753)]} 0 2
[junit4:junit4]   2> 4559 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[84 (1412976689783242752)]} 0 3
[junit4:junit4]   2> 4561 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[74 (-1412976689787437057)]} 0 1
[junit4:junit4]   2> 4561 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10094 (1412976689787437056)]} 0 1
[junit4:junit4]   2> 4565 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4565 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[74 (-1412976689790582784)]} 0 2
[junit4:junit4]   2> 4566 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4566 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10094 (1412976689790582785)]} 0 3
[junit4:junit4]   2> 4567 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[85 (1412976689794777088)]} 0 0
[junit4:junit4]   2> 4568 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10092 (-1412976689795825664)]} 0 0
[junit4:junit4]   2> 4571 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4572 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10092 (-1412976689797922816)]} 0 2
[junit4:junit4]   2> 4576 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 4577 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[85 (1412976689796874240)]} 0 8
[junit4:junit4]   2> 4579 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[77 (-1412976689807360000)]} 0 0
[junit4:junit4]   2> 4581 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10095 (1412976689802117120)]} 0 7
[junit4:junit4]   2> 4583 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4583 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[77 (-1412976689809457152)]} 0 2
[junit4:junit4]   2> 4586 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4586 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[86 (1412976689813651456)]} 0 1
[junit4:junit4]   2> 4591 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10095 (1412976689811554304)]} 0 8
[junit4:junit4]   2> 4593 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10093 (-1412976689822040064)]} 0 0
[junit4:junit4]   2> 4595 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4596 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[86 (1412976689822040064)]} 0 3
[junit4:junit4]   2> 4597 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4597 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10093 (-1412976689824137216)]} 0 2
[junit4:junit4]   2> 4598 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[87 (1412976689827282944)]} 0 1
[junit4:junit4]   2> 4600 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10096 (1412976689828331520)]} 0 1
[junit4:junit4]   2> 4602 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4603 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[87 (1412976689829380096)]} 0 3
[junit4:junit4]   2> 4604 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4605 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10096 (1412976689831477248)]} 0 3
[junit4:junit4]   2> 4605 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[83 (-1412976689834622976)]} 0 0
[junit4:junit4]   2> 4607 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10096 (-1412976689835671552)]} 0 1
[junit4:junit4]   2> 4608 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4609 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[83 (-1412976689836720128)]} 0 2
[junit4:junit4]   2> 4610 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4611 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[10096 (-1412976689838817280)]} 0 3
[junit4:junit4]   2> 4611 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[88 (1412976689840914432)]} 0 0
[junit4:junit4]   2> 4613 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10097 (1412976689843011584)]} 0 0
[junit4:junit4]   2> 4618 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4618 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10097 (1412976689845108736)]} 0 3
[junit4:junit4]   2> 4620 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10097 (-1412976689850351616)]} 0 0
[junit4:junit4]   2> 4624 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4624 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4624 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10097 (-1412976689852448768)]} 0 2
[junit4:junit4]   2> 4624 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[88 (1412976689843011584)]} 0 11
[junit4:junit4]   2> 4626 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[84 (-1412976689856643073)]} 0 0
[junit4:junit4]   2> 4626 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10098 (1412976689856643072)]} 0 0
[junit4:junit4]   2> 4630 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4631 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4631 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[84 (-1412976689858740225)]} 0 3
[junit4:junit4]   2> 4631 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10098 (1412976689858740224)]} 0 3
[junit4:junit4]   2> 4633 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10098 (-1412976689863983105)]} 0 0
[junit4:junit4]   2> 4633 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[89 (1412976689863983104)]} 0 0
[junit4:junit4]   2> 4637 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4637 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10098 (-1412976689866080256)]} 0 2
[junit4:junit4]   2> 4638 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4639 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[89 (1412976689866080257)]} 0 4
[junit4:junit4]   2> 4640 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10099 (1412976689870274560)]} 0 1
[junit4:junit4]   2> 4641 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[87 (-1412976689872371712)]} 0 0
[junit4:junit4]   2> 4644 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4644 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10099 (1412976689872371712)]} 0 3
[junit4:junit4]   2> 4645 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4645 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[87 (-1412976689874468864)]} 0 2
[junit4:junit4]   2> 4646 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10099 (-1412976689877614592)]} 0 0
[junit4:junit4]   2> 4647 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[90 (1412976689878663168)]} 0 0
[junit4:junit4]   2> 4650 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4651 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10099 (-1412976689879711744)]} 0 3
[junit4:junit4]   2> 4657 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4657 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[90 (1412976689880760320)]} 0 8
[junit4:junit4]   2> 4658 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10100 (1412976689883906048)]} 0 6
[junit4:junit4]   2> 4661 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[91 (1412976689891246080)]} 0 2
[junit4:junit4]   2> 4663 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4664 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10100 (1412976689892294656)]} 0 4
[junit4:junit4]   2> 4665 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4665 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[91 (1412976689894391808)]} 0 3
[junit4:junit4]   2> 4666 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10100 (-1412976689898586112)]} 0 1
[junit4:junit4]   2> 4668 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[92 (1412976689899634688)]} 0 1
[junit4:junit4]   2> 4669 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4670 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10100 (-1412976689900683264)]} 0 3
[junit4:junit4]   2> 4672 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4672 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10101 (1412976689904877568)]} 0 1
[junit4:junit4]   2> 4672 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[92 (1412976689902780416)]} 0 2
[junit4:junit4]   2> 4675 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[93 (1412976689906974720)]} 0 1
[junit4:junit4]   2> 4676 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4677 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10101 (1412976689906974720)]} 0 3
[junit4:junit4]   2> 4679 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4679 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10102 (1412976689911169024)]} 0 1
[junit4:junit4]   2> 4679 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[93 (1412976689910120448)]} 0 2
[junit4:junit4]   2> 4681 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[88 (-1412976689914314752)]} 0 0
[junit4:junit4]   2> 4683 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4684 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10102 (1412976689914314752)]} 0 3
[junit4:junit4]   2> 4685 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4686 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[88 (-1412976689916411904)]} 0 3
[junit4:junit4]   2> 4686 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10103 (1412976689919557632)]} 0 0
[junit4:junit4]   2> 4688 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[94 (1412976689921654784)]} 0 1
[junit4:junit4]   2> 4690 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4691 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10103 (1412976689921654784)]} 0 3
[junit4:junit4]   2> 4692 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4692 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[94 (1412976689923751936)]} 0 2
[junit4:junit4]   2> 4693 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10104 (1412976689926897664)]} 0 0
[junit4:junit4]   2> 4694 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[92 (-1412976689927946240)]} 0 0
[junit4:junit4]   2> 4697 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4697 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4698 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10104 (1412976689928994816)]} 0 3
[junit4:junit4]   2> 4698 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[92 (-1412976689930043392)]} 0 2
[junit4:junit4]   2> 4700 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10105 (1412976689934237696)]} 0 0
[junit4:junit4]   2> 4708 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[95 (1412976689934237697)]} 0 8
[junit4:junit4]   2> 4717 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 4717 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10105 (1412976689936334848)]} 0 15
[junit4:junit4]   2> 4719 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10105 (-1412976689954160640)]} 0 0
[junit4:junit4]   2> 4721 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4722 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[95 (1412976689944723456)]} 0 12
[junit4:junit4]   2> 4723 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4724 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10105 (-1412976689956257792)]} 0 3
[junit4:junit4]   2> 4724 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[93 (-1412976689959403520)]} 0 0
[junit4:junit4]   2> 4727 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10106 (1412976689961500672)]} 0 1
[junit4:junit4]   2> 4728 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4728 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[93 (-1412976689961500672)]} 0 2
[junit4:junit4]   2> 4731 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[96 (1412976689965694976)]} 0 1
[junit4:junit4]   2> 4732 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4733 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10106 (1412976689964646400)]} 0 4
[junit4:junit4]   2> 4735 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10107 (1412976689970937856)]} 0 0
[junit4:junit4]   2> 4736 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4736 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[96 (1412976689968840704)]} 0 4
[junit4:junit4]   2> 4738 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[96 (-1412976689974083584)]} 0 0
[junit4:junit4]   2> 4740 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4740 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10107 (1412976689973035008)]} 0 3
[junit4:junit4]   2> 4742 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4742 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[96 (-1412976689976180736)]} 0 2
[junit4:junit4]   2> 4743 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10107 (-1412976689979326464)]} 0 1
[junit4:junit4]   2> 4745 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[97 (1412976689980375040)]} 0 1
[junit4:junit4]   2> 4747 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4747 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10107 (-1412976689981423616)]} 0 2
[junit4:junit4]   2> 4750 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4750 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10108 (1412976689986666496)]} 0 0
[junit4:junit4]   2> 4750 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[97 (1412976689983520768)]} 0 3
[junit4:junit4]   2> 4753 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[97 (-1412976689989812224)]} 0 1
[junit4:junit4]   2> 4755 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4756 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10108 (1412976689989812224)]} 0 4
[junit4:junit4]   2> 4757 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4757 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[97 (-1412976689991909376)]} 0 2
[junit4:junit4]   2> 4758 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10109 (1412976689994006528)]} 0 1
[junit4:junit4]   2> 4759 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[98 (1412976689996103680)]} 0 0
[junit4:junit4]   2> 4762 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4762 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10109 (1412976689997152256)]} 0 3
[junit4:junit4]   2> 4764 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4764 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[98 (1412976689998200832)]} 0 3
[junit4:junit4]   2> 4765 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10110 (1412976690001346560)]} 0 1
[junit4:junit4]   2> 4766 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[98 (-1412976690003443712)]} 0 0
[junit4:junit4]   2> 4769 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4769 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10110 (1412976690004492288)]} 0 2
[junit4:junit4]   2> 4770 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4770 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[98 (-1412976690005540864)]} 0 2
[junit4:junit4]   2> 4772 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10111 (1412976690008686592)]} 0 1
[junit4:junit4]   2> 4780 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[99 (1412976690009735168)]} 0 8
[junit4:junit4]   2> 4783 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 4784 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10111 (1412976690011832320)]} 0 11
[junit4:junit4]   2> 4785 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4786 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[99 (1412976690020220928)]} 0 4
[junit4:junit4]   2> 4786 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10111 (-1412976690024415232)]} 0 0
[junit4:junit4]   2> 4788 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[99 (-1412976690026512384)]} 0 0
[junit4:junit4]   2> 4790 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4790 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10111 (-1412976690026512384)]} 0 2
[junit4:junit4]   2> 4792 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4792 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[99 (-1412976690028609536)]} 0 2
[junit4:junit4]   2> 4793 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10112 (1412976690030706688)]} 0 1
[junit4:junit4]   2> 4795 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[100 (1412976690033852416)]} 0 1
[junit4:junit4]   2> 4798 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4798 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10112 (1412976690033852416)]} 0 3
[junit4:junit4]   2> 4799 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4800 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[100 (1412976690035949568)]} 0 3
[junit4:junit4]   2> 4800 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10112 (-1412976690039095296)]} 0 0
[junit4:junit4]   2> 4802 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[100 (-1412976690041192448)]} 0 0
[junit4:junit4]   2> 4804 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4804 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10112 (-1412976690041192448)]} 0 2
[junit4:junit4]   2> 4806 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4806 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[100 (-1412976690043289600)]} 0 2
[junit4:junit4]   2> 4806 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10113 (1412976690045386752)]} 0 0
[junit4:junit4]   2> 4808 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[101 (1412976690047483904)]} 0 0
[junit4:junit4]   2> 4811 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4811 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10113 (1412976690047483904)]} 0 3
[junit4:junit4]   2> 4813 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10114 (1412976690052726784)]} 0 0
[junit4:junit4]   2> 4814 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4814 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[101 (1412976690050629632)]} 0 3
[junit4:junit4]   2> 4816 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[102 (1412976690055872512)]} 0 0
[junit4:junit4]   2> 4818 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4818 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10114 (1412976690055872512)]} 0 3
[junit4:junit4]   2> 4821 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4821 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10115 (1412976690060066816)]} 0 1
[junit4:junit4]   2> 4821 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[102 (1412976690057969664)]} 0 3
[junit4:junit4]   2> 4823 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[103 (1412976690063212544)]} 0 0
[junit4:junit4]   2> 4826 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4826 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[10115 (1412976690063212544)]} 0 3
[junit4:junit4]   2> 4828 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4828 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[103 (1412976690065309696)]} 0 3
[junit4:junit4]   2> 4829 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10116 (1412976690068455424)]} 0 1
[junit4:junit4]   2> 4850 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 4850 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10116 (1412976690071601152)]} 0 20
[junit4:junit4]   2> 4853 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10114 (-1412976690094669824)]} 0 0
[junit4:junit4]   2> 4857 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4858 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10114 (-1412976690096766976)]} 0 3
[junit4:junit4]   2> 4861 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10117 (1412976690102009856)]} 0 1
[junit4:junit4]   2> 4868 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4868 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10117 (1412976690105155584)]} 0 5
[junit4:junit4]   2> 4871 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10118 (1412976690113544192)]} 0 1
[junit4:junit4]   2> 4873 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[104 (1412976690070552576)]} 0 43
[junit4:junit4]   2> 4883 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4883 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10118 (1412976690115641344)]} 0 10
[junit4:junit4]   2> 4885 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10119 (1412976690128224256)]} 0 0
[junit4:junit4]   2> 4890 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4890 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10119 (1412976690130321408)]} 0 3
[junit4:junit4]   2> 4892 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10120 (1412976690135564288)]} 0 0
[junit4:junit4]   2> 4897 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4898 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10120 (1412976690137661440)]} 0 4
[junit4:junit4]   2> 4900 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10121 (1412976690143952896)]} 0 0
[junit4:junit4]   2> 4905 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4906 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10121 (1412976690147098624)]} 0 3
[junit4:junit4]   2> 4908 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10119 (-1412976690152341504)]} 0 0
[junit4:junit4]   2> 4918 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4919 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10119 (-1412976690161778688)]} 0 2
[junit4:junit4]   2> 4921 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10122 (1412976690165972992)]} 0 0
[junit4:junit4]   2> 4926 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4927 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10122 (1412976690169118720)]} 0 4
[junit4:junit4]   2> 4930 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[10122 (-1412976690174361600)]} 0 1
[junit4:junit4]   2> 4934 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4935 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10122 (-1412976690177507328)]} 0 3
[junit4:junit4]   2> 4937 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10123 (1412976690182750208)]} 0 0
[junit4:junit4]   2> 4941 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4942 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[104 (1412976690125078528)]} 0 60
[junit4:junit4]   2> 4943 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4943 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10123 (1412976690185895936)]} 0 3
[junit4:junit4]   2> 4945 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10123 (-1412976690191138816)]} 0 0
[junit4:junit4]   2> 4946 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[105 (1412976690190090240)]} 0 2
[junit4:junit4]   2> 4949 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4950 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10123 (-1412976690193235968)]} 0 3
[junit4:junit4]   2> 4952 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4952 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[10124 (1412976690198478848)]} 0 0
[junit4:junit4]   2> 4953 T1245 C78 P45404 /update {wt=javabin&version=2} {add=[105 (1412976690194284544)]} 0 5
[junit4:junit4]   2> 4955 T1229 C77 P43284 /update {wt=javabin&version=2} {delete=[105 (-1412976690201624576)]} 0 0
[junit4:junit4]   2> 4959 T1255 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4968 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 4960 T1245 C78 P45404 /update {wt=javabin&version=2} {delete=[105 (-1412976690203721728)]} 0 3
[junit4:junit4]   2> 4969 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10124 (1412976690201624576)]} 0 14
[junit4:junit4]   2> 4971 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10124 (-1412976690218401793)]} 0 0
[junit4:junit4]   2> 4975 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4976 T1244 C78 P45404 /update {wt=javabin&version=2} {delete=[10124 (-1412976690220498944)]} 0 3
[junit4:junit4]   2> 4979 T1228 C77 P43284 /update {wt=javabin&version=2} {add=[10125 (1412976690225741824)]} 0 1
[junit4:junit4]   2> 4985 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4986 T1244 C78 P45404 /update {wt=javabin&version=2} {add=[10125 (1412976690229936128)]} 0 4
[junit4:junit4]   2> 4988 T1229 C77 P43284 /update {wt=javabin&version=2} {add=[106 (1412976690218401792)]} 0 18
[junit4:junit4]   2> 4988 T1228 C77 P43284 /update {wt=javabin&version=2} {delete=[10125 (-1412976690236227584)]} 0 0
[junit4:junit4]   2> 4992 T1254 C76 P41496 REQ /update {distrib.from=http://127.0.0.1:45404/solr/collection1/&update.distrib=F

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

:junit4]   2> 25072 T1216 oaz.ZooKeeper.close Session: 0x139be6cf0a00003 closed
[junit4:junit4]   2> 25073 T1216 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 25126 T1216 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 41496
[junit4:junit4]   2> 25126 T1216 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=21953042
[junit4:junit4]   2> 25829 T1219 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 26384 T1390 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55486
[junit4:junit4]   2> 26485 T1391 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 26485 T1216 oaz.ZooKeeper.close Session: 0x139be6cf0a00007 closed
[junit4:junit4]   2> 26486 T1216 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 26589 T1216 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=C214BA3409979D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_BA -Dtests.timezone=Europe/Guernsey -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   26.6s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://localhost:41496/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([C214BA3409979D:81249AA24356F7A1]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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> 26604 T1216 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {range_facet_l=PostingsFormat(name=NestedPulsing), text=PostingsFormat(name=Memory doPackFST= false), _version_=PostingsFormat(name=NestedPulsing), rnd_b=PostingsFormat(name=MockSep), intDefault=PostingsFormat(name=NestedPulsing), timestamp=PostingsFormat(name=NestedPulsing), id=PostingsFormat(name=MockSep), a_t=PostingsFormat(name=NestedPulsing), range_facet_sl=PostingsFormat(name=Memory doPackFST= false), range_facet_si=PostingsFormat(name=Direct), other_tl1=PostingsFormat(name=NestedPulsing), multiDefault=PostingsFormat(name=Direct), a_si=PostingsFormat(name=Direct)}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=sr_BA, timezone=Europe/Guernsey
[junit4:junit4]   2> NOTE: Linux 3.2.0-29-generic i386/Oracle Corporation 1.7.0_07 (32-bit)/cpus=8,threads=1,free=43749984,total=116633600
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestPhraseSuggestions, PeerSyncTest, URLClassifyProcessorTest, TestSurroundQueryParser, StatsComponentTest, BasicDistributedZk2Test, OpenExchangeRatesOrgProviderTest, PingRequestHandlerTest, TestRealTimeGet, TestReplicationHandler, ZkNodePropsTest, TestValueSourceCache, BasicFunctionalityTest, TestRTGBase, TestBM25SimilarityFactory, TestBinaryField, SuggesterTSTTest, TermVectorComponentTest, HighlighterTest, PrimitiveFieldTypeTest, StandardRequestHandlerTest, ZkSolrClientTest, FieldMutatingUpdateProcessorTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, SyncSliceTest, DirectUpdateHandlerTest, StatelessScriptUpdateProcessorFactoryTest, TestTrie, TestDocSet, TestDistributedGrouping, TestStressLucene, FullSolrCloudDistribCmdsTest, ScriptEngineTest, XsltUpdateRequestHandlerTest, TestCodecSupport, PrimUtilsTest, TestLRUCache, PolyFieldTest, TestWriterPerf, TestSort, TestRemoteStreaming, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 26.63s, 1 test, 1 error <<< FAILURES!

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

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



Mime
View raw message