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.6.0_37) - Build # 2153 - Failure!
Date Fri, 02 Nov 2012 13:39:54 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2153/
Java: 32bit/jdk1.6.0_37 -server -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest

Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1364, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.

Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1364, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	at __randomizedtesting.SeedInfo.seed([2B71EE100690CF68]:0)
	at java.lang.Thread.getStackTrace(Thread.java:1479)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:514)
	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:958)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:872)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:864)
	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:232)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:192)
	at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:373)
	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:273)
	at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:152)
	at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:407)
	at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:222)
Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.RAMDirectory@13b7091 lockFactory=org.apache.lucene.store.SimpleFSLockFactory@169660b)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:662)




Build Log:
[...truncated 8039 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 3 T1314 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-1351863175586
[junit4:junit4]   2> 4 T1314 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 5 T1315 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 5 T1315 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 6 T1315 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 105 T1314 oasc.ZkTestServer.run start zk server on port:41057
[junit4:junit4]   2> 105 T1314 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41057 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@f33bb
[junit4:junit4]   2> 107 T1320 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41057
[junit4:junit4]   2> 107 T1314 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 108 T1320 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41057, initiating session
[junit4:junit4]   2> 108 T1316 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51678
[junit4:junit4]   2> 108 T1316 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51678
[junit4:junit4]   2> 108 T1318 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 179 T1318 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac15309a40000 with negotiated timeout 10000 for client /127.0.0.1:51678
[junit4:junit4]   2> 179 T1320 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41057, sessionid = 0x13ac15309a40000, negotiated timeout = 10000
[junit4:junit4]   2> 179 T1321 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f33bb name:ZooKeeperConnection Watcher:127.0.0.1:41057 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 179 T1314 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 179 T1314 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 182 T1319 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ac15309a40000
[junit4:junit4]   2> 182 T1321 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 182 T1316 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51678 which had sessionid 0x13ac15309a40000
[junit4:junit4]   2> 182 T1314 oaz.ZooKeeper.close Session: 0x13ac15309a40000 closed
[junit4:junit4]   2> 183 T1314 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41057/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1821e76
[junit4:junit4]   2> 183 T1322 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41057
[junit4:junit4]   2> 183 T1314 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 184 T1322 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41057, initiating session
[junit4:junit4]   2> 184 T1316 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51679
[junit4:junit4]   2> 184 T1316 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51679
[junit4:junit4]   2> 185 T1318 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac15309a40001 with negotiated timeout 10000 for client /127.0.0.1:51679
[junit4:junit4]   2> 185 T1322 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41057, sessionid = 0x13ac15309a40001, negotiated timeout = 10000
[junit4:junit4]   2> 185 T1323 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1821e76 name:ZooKeeperConnection Watcher:127.0.0.1:41057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 185 T1314 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 186 T1314 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 188 T1314 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 191 T1314 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 192 T1314 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 193 T1314 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> 193 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 196 T1314 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> 197 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 199 T1314 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> 199 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 201 T1314 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> 202 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 203 T1314 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> 204 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 205 T1314 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> 206 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 207 T1314 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> 207 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 209 T1314 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> 209 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 212 T1314 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> 213 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 214 T1314 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> 215 T1314 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 216 T1319 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ac15309a40001
[junit4:junit4]   2> 217 T1323 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 217 T1316 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51679 which had sessionid 0x13ac15309a40001
[junit4:junit4]   2> 217 T1314 oaz.ZooKeeper.close Session: 0x13ac15309a40001 closed
[junit4:junit4]   2> 294 T1314 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 297 T1314 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:33594
[junit4:junit4]   2> 298 T1314 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 299 T1314 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799
[junit4:junit4]   2> 299 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/'
[junit4:junit4]   2> 315 T1314 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 315 T1314 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 316 T1314 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799
[junit4:junit4]   2> 316 T1314 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/solr.xml
[junit4:junit4]   2> 316 T1314 oasc.CoreContainer.<init> New CoreContainer 13441345
[junit4:junit4]   2> 317 T1314 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/'
[junit4:junit4]   2> 317 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/'
[junit4:junit4]   2> 330 T1314 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 339 T1314 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41057/solr
[junit4:junit4]   2> 340 T1314 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41057/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@227fbe
[junit4:junit4]   2> 341 T1333 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41057
[junit4:junit4]   2> 341 T1314 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 341 T1333 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41057, initiating session
[junit4:junit4]   2> 341 T1316 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51680
[junit4:junit4]   2> 341 T1316 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51680
[junit4:junit4]   2> 342 T1318 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac15309a40002 with negotiated timeout 5000 for client /127.0.0.1:51680
[junit4:junit4]   2> 342 T1333 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41057, sessionid = 0x13ac15309a40002, negotiated timeout = 5000
[junit4:junit4]   2> 342 T1334 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@227fbe name:ZooKeeperConnection Watcher:127.0.0.1:41057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 342 T1314 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 343 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 344 T1314 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 345 T1314 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33594_solr
[junit4:junit4]   2> 346 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:33594_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:33594_solr
[junit4:junit4]   2> 346 T1314 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33594_solr
[junit4:junit4]   2> 348 T1314 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 348 T1314 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 348 T1314 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 349 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 349 T1314 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 350 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 350 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 351 T1314 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 351 T1314 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 352 T1314 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 355 T1314 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 358 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 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> 359 T1314 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 362 T1314 oasc.Overseer.start Overseer (id=88595705075335170-127.0.0.1:33594_solr-n_0000000000) starting
[junit4:junit4]   2> 362 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 363 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 363 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 364 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 364 T1336 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 365 T1314 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 366 T1314 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 367 T1335 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 372 T1314 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/collection1
[junit4:junit4]   2> 373 T1314 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 373 T1314 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 374 T1314 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 375 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/collection1/'
[junit4:junit4]   2> 375 T1314 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/collection1/lib/README' to classloader
[junit4:junit4]   2> 376 T1314 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 419 T1314 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 474 T1314 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 475 T1314 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 481 T1314 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 929 T1314 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 936 T1314 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 939 T1314 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 950 T1314 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 954 T1314 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 959 T1314 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 960 T1314 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 961 T1314 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351863175799/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351863175586/control/data/
[junit4:junit4]   2> 962 T1314 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4c71d2
[junit4:junit4]   2> 962 T1314 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 964 T1314 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index3256032577tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@56dc3a) assuming 'simple'
[junit4:junit4]   2> 965 T1314 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-1351863175586/control/data forceNew:false
[junit4:junit4]   2> 966 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/control/data
[junit4:junit4]   2> 966 T1314 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351863175586/control/data/index/
[junit4:junit4]   2> 967 T1314 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351863175586/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 968 T1314 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-1351863175586/control/data/index forceNew:false
[junit4:junit4]   2> 970 T1314 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1670bbd lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d7b2d1),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 971 T1314 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 972 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/control/data/index
[junit4:junit4]   2> 972 T1314 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 975 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 975 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 976 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 977 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 978 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 978 T1314 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 979 T1314 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 980 T1314 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 982 T1314 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 983 T1314 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 984 T1314 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 985 T1314 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 986 T1314 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 987 T1314 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 988 T1314 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 989 T1314 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 990 T1314 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 992 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 993 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 994 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 996 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 997 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 998 T1314 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1000 T1314 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1001 T1314 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1004 T1314 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1007 T1314 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1009 T1314 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> 1011 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/control/data
[junit4:junit4]   2> 1012 T1314 oass.SolrIndexSearcher.<init> Opening Searcher@3b4aa2 main
[junit4:junit4]   2> 1013 T1314 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1014 T1314 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1014 T1314 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1020 T1314 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1044 T1337 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3b4aa2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1048 T1314 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1048 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1050 T1314 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 1050 T1314 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:33594/solr shardId:control_shard
[junit4:junit4]   2> 1051 T1314 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1054 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:delete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1054 T1314 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1055 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1371 T1335 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1371 T1335 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33594_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33594/solr"}
[junit4:junit4]   2> 1372 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1375 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1556 T1314 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1557 T1314 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1557 T1314 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:33594/solr/collection1/
[junit4:junit4]   2> 1557 T1314 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1558 T1314 oasc.SyncStrategy.syncToMe http://127.0.0.1:33594/solr/collection1/ has no replicas
[junit4:junit4]   2> 1558 T1314 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:33594/solr/collection1/
[junit4:junit4]   2> 1559 T1314 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1562 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40002 type:create cxid:0x89 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1878 T1335 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1880 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1914 T1314 oasc.ZkController.register We are http://127.0.0.1:33594/solr/collection1/ and leader is http://127.0.0.1:33594/solr/collection1/
[junit4:junit4]   2> 1915 T1314 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33594/solr
[junit4:junit4]   2> 1915 T1314 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1916 T1314 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1917 T1314 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1918 T1314 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1918 T1314 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1919 T1314 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1923 T1314 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 1927 T1314 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41057/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1330b8a
[junit4:junit4]   2> 1928 T1338 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41057
[junit4:junit4]   2> 1928 T1314 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1929 T1338 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41057, initiating session
[junit4:junit4]   2> 1929 T1316 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51681
[junit4:junit4]   2> 1929 T1316 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51681
[junit4:junit4]   2> 1930 T1318 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac15309a40003 with negotiated timeout 10000 for client /127.0.0.1:51681
[junit4:junit4]   2> 1930 T1338 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41057, sessionid = 0x13ac15309a40003, negotiated timeout = 10000
[junit4:junit4]   2> 1930 T1339 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1330b8a name:ZooKeeperConnection Watcher:127.0.0.1:41057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1930 T1314 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1931 T1314 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1932 T1314 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 2004 T1314 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2006 T1314 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:48370
[junit4:junit4]   2> 2006 T1314 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2007 T1314 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514
[junit4:junit4]   2> 2007 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/'
[junit4:junit4]   2> 2019 T1314 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2020 T1314 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2020 T1314 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514
[junit4:junit4]   2> 2020 T1314 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/solr.xml
[junit4:junit4]   2> 2021 T1314 oasc.CoreContainer.<init> New CoreContainer 29371951
[junit4:junit4]   2> 2021 T1314 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/'
[junit4:junit4]   2> 2021 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/'
[junit4:junit4]   2> 2035 T1314 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2044 T1314 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41057/solr
[junit4:junit4]   2> 2044 T1314 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41057/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@b051e2
[junit4:junit4]   2> 2045 T1349 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41057
[junit4:junit4]   2> 2045 T1314 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2045 T1349 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41057, initiating session
[junit4:junit4]   2> 2046 T1316 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51682
[junit4:junit4]   2> 2046 T1316 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51682
[junit4:junit4]   2> 2046 T1318 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac15309a40004 with negotiated timeout 5000 for client /127.0.0.1:51682
[junit4:junit4]   2> 2046 T1349 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41057, sessionid = 0x13ac15309a40004, negotiated timeout = 5000
[junit4:junit4]   2> 2047 T1350 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b051e2 name:ZooKeeperConnection Watcher:127.0.0.1:41057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2047 T1314 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2047 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2048 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2048 T1314 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48370_solr
[junit4:junit4]   2> 2049 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48370_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48370_solr
[junit4:junit4]   2> 2049 T1314 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48370_solr
[junit4:junit4]   2> 2050 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2051 T1339 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2050 T1314 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2051 T1314 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2051 T1334 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2051 T1314 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2052 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2052 T1314 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2052 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2053 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2053 T1314 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2053 T1314 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2054 T1314 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2057 T1314 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2063 T1314 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/collection1
[junit4:junit4]   2> 2064 T1314 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2064 T1314 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2064 T1314 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2065 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/collection1/'
[junit4:junit4]   2> 2066 T1314 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/collection1/lib/README' to classloader
[junit4:junit4]   2> 2066 T1314 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2099 T1314 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2141 T1314 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2142 T1314 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2146 T1314 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2383 T1335 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2383 T1335 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33594_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33594/solr"}
[junit4:junit4]   2> 2386 T1339 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2386 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2386 T1350 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2536 T1314 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2543 T1314 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2546 T1314 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2554 T1314 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2558 T1314 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2564 T1314 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2566 T1314 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2567 T1314 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351863177514/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty1/
[junit4:junit4]   2> 2568 T1314 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4c71d2
[junit4:junit4]   2> 2569 T1314 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2570 T1314 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@16ca004 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7da3dc) assuming 'simple'
[junit4:junit4]   2> 2571 T1314 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-1351863175586/jetty1 forceNew:false
[junit4:junit4]   2> 2572 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty1
[junit4:junit4]   2> 2572 T1314 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty1/index/
[junit4:junit4]   2> 2573 T1314 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2574 T1314 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-1351863175586/jetty1/index forceNew:false
[junit4:junit4]   2> 2575 T1314 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@ec941d lockFactory=org.apache.lucene.store.NativeFSLockFactory@15e9358),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2576 T1314 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 2576 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty1/index
[junit4:junit4]   2> 2577 T1314 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2579 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2579 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2580 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2580 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2581 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2581 T1314 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2582 T1314 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2583 T1314 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2584 T1314 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2585 T1314 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2586 T1314 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2586 T1314 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2587 T1314 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2588 T1314 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2588 T1314 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2589 T1314 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2590 T1314 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2591 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2592 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2593 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2594 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2596 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2597 T1314 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2598 T1314 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2599 T1314 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2601 T1314 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2603 T1314 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2604 T1314 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> 2610 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty1
[junit4:junit4]   2> 2610 T1314 oass.SolrIndexSearcher.<init> Opening Searcher@11cee21 main
[junit4:junit4]   2> 2611 T1314 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2611 T1314 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2612 T1314 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2932 T1314 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2955 T1351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11cee21 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2959 T1314 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3390 T1335 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3391 T1335 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48370_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48370/solr"}
[junit4:junit4]   2> 3391 T1335 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 3393 T1339 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3393 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3393 T1350 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3461 T1314 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 3461 T1314 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:48370/solr shardId:shard1
[junit4:junit4]   2> 3462 T1314 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3465 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40004 type:delete cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3466 T1314 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3466 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40004 type:create cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3467 T1314 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3467 T1314 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3468 T1314 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48370/solr/collection1/
[junit4:junit4]   2> 3468 T1314 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3468 T1314 oasc.SyncStrategy.syncToMe http://127.0.0.1:48370/solr/collection1/ has no replicas
[junit4:junit4]   2> 3469 T1314 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48370/solr/collection1/
[junit4:junit4]   2> 3469 T1314 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3471 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40004 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3895 T1335 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3899 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3899 T1339 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3899 T1350 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3923 T1314 oasc.ZkController.register We are http://127.0.0.1:48370/solr/collection1/ and leader is http://127.0.0.1:48370/solr/collection1/
[junit4:junit4]   2> 3923 T1314 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48370/solr
[junit4:junit4]   2> 3924 T1314 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3924 T1314 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3925 T1314 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3926 T1314 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3926 T1314 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3926 T1314 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3983 T1314 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3985 T1314 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:37516
[junit4:junit4]   2> 3985 T1314 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3986 T1314 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511
[junit4:junit4]   2> 3986 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/'
[junit4:junit4]   2> 3998 T1314 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3998 T1314 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3998 T1314 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511
[junit4:junit4]   2> 3999 T1314 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/solr.xml
[junit4:junit4]   2> 3999 T1314 oasc.CoreContainer.<init> New CoreContainer 10263397
[junit4:junit4]   2> 3999 T1314 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/'
[junit4:junit4]   2> 4000 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/'
[junit4:junit4]   2> 4012 T1314 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4021 T1314 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41057/solr
[junit4:junit4]   2> 4022 T1314 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41057/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@325c4b
[junit4:junit4]   2> 4028 T1361 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:41057
[junit4:junit4]   2> 4028 T1314 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4028 T1361 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41057, initiating session
[junit4:junit4]   2> 4028 T1316 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51684
[junit4:junit4]   2> 4028 T1316 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51684
[junit4:junit4]   2> 4030 T1318 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ac15309a40005 with negotiated timeout 5000 for client /127.0.0.1:51684
[junit4:junit4]   2> 4030 T1361 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:41057, sessionid = 0x13ac15309a40005, negotiated timeout = 5000
[junit4:junit4]   2> 4030 T1362 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@325c4b name:ZooKeeperConnection Watcher:127.0.0.1:41057/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4031 T1314 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4031 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4032 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4032 T1314 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37516_solr
[junit4:junit4]   2> 4033 T1319 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ac15309a40005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37516_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37516_solr
[junit4:junit4]   2> 4033 T1314 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37516_solr
[junit4:junit4]   2> 4034 T1339 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4034 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4034 T1350 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4034 T1350 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4035 T1314 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4035 T1334 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4035 T1339 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4035 T1314 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4036 T1314 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4036 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4036 T1314 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4041 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4042 T1314 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4042 T1314 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4042 T1314 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4043 T1314 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4048 T1314 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4052 T1314 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/collection1
[junit4:junit4]   2> 4052 T1314 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4053 T1314 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4053 T1314 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4054 T1314 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/collection1/'
[junit4:junit4]   2> 4055 T1314 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/collection1/lib/README' to classloader
[junit4:junit4]   2> 4055 T1314 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4092 T1314 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4134 T1314 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4135 T1314 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4139 T1314 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4402 T1335 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4403 T1335 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48370_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48370/solr"}
[junit4:junit4]   2> 4405 T1339 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4405 T1362 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4405 T1350 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4406 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4509 T1314 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4515 T1314 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4518 T1314 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4527 T1314 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4531 T1314 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4535 T1314 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4536 T1314 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4537 T1314 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351863179511/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2/
[junit4:junit4]   2> 4538 T1314 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4c71d2
[junit4:junit4]   2> 4539 T1314 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 4541 T1314 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for BaseDirectoryWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index3256032578tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@1508a71) assuming 'simple'
[junit4:junit4]   2> 4541 T1314 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-1351863175586/jetty2 forceNew:false
[junit4:junit4]   2> 4542 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2
[junit4:junit4]   2> 4542 T1314 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2/index/
[junit4:junit4]   2> 4542 T1314 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4543 T1314 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-1351863175586/jetty2/index forceNew:false
[junit4:junit4]   2> 4544 T1314 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ec93cf lockFactory=org.apache.lucene.store.NativeFSLockFactory@f7be6a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4545 T1314 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4545 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2/index
[junit4:junit4]   2> 4546 T1314 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4547 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4549 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4550 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4550 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4551 T1314 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4551 T1314 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4552 T1314 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4553 T1314 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4554 T1314 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4555 T1314 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4556 T1314 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4557 T1314 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4558 T1314 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4558 T1314 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4559 T1314 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4560 T1314 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4561 T1314 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4562 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4563 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4564 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4565 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4566 T1314 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4567 T1314 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4568 T1314 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4569 T1314 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4571 T1314 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4573 T1314 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4575 T1314 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> 4577 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2
[junit4:junit4]   2> 4577 T1314 oass.SolrIndexSearcher.<init> Opening Searcher@16008b8 main
[junit4:junit4]   2> 4578 T1314 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4578 T1314 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4579 T1314 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4583 T1314 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4610 T1363 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16008b8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4613 T1314 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4908 T1335 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4909 T1335 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37516_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37516/solr"}
[junit4:junit4]   2> 4911 T1339 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4911 T1350 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4911 T1362 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4911 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5114 T1314 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 5115 T1314 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:37516/solr shardId:shard1
[junit4:junit4]   2> 5117 T1314 oasc.ZkController.register We are http://127.0.0.1:37516/solr/collection1/ and leader is http://127.0.0.1:48370/solr/collection1/
[junit4:junit4]   2> 5118 T1314 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37516/solr
[junit4:junit4]   2> 5118 T1314 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 5118 T1314 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C82 name=collection1 org.apache.solr.core.SolrCore@186804a url=http://127.0.0.1:37516/solr/collection1 node=127.0.0.1:37516_solr
[junit4:junit4]   2> 5122 T1364 C82 P37516 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 5123 T1364 C82 P37516 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 5122 T1314 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5123 T1364 C82 P37516 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5125 T1314 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 5125 T1364 C82 P37516 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:48370/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 5125 T1314 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5125 T1364 C82 P37516 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:37516/solr START replicas=[http://127.0.0.1:48370/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 5125 T1314 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5126 T1364 C82 P37516 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 5130 T1314 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5133 T1364 C82 P37516 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C83 name=collection1 org.apache.solr.core.SolrCore@1b3298b url=http://127.0.0.1:48370/solr/collection1 node=127.0.0.1:48370_solr
[junit4:junit4]   2> 5133 T1348 C83 P48370 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=1 
[junit4:junit4]   2> 5133 T1364 C82 P37516 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 5134 T1364 C82 P37516 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C84 name=collection1 org.apache.solr.core.SolrCore@f146ac url=http://127.0.0.1:33594/solr/collection1 node=127.0.0.1:33594_solr
[junit4:junit4]   2> 5139 T1329 C84 P33594 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/control/data
[junit4:junit4]   2> 5141 T1329 C84 P33594 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1670bbd lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d7b2d1),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5141 T1329 C84 P33594 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5143 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[1 (1417531286586654720)]} 0 5
[junit4:junit4]   2> 5143 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10001 (1417531286588751872)]} 0 3
[junit4:junit4]   2> 5146 T1347 C83 P48370 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty1
[junit4:junit4]   2> 5148 T1347 C83 P48370 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@ec941d lockFactory=org.apache.lucene.store.NativeFSLockFactory@15e9358),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5148 T1347 C83 P48370 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5150 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[1 (1417531286595043329)]} 0 4
[junit4:junit4]   2> 5150 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10001 (1417531286595043328)]} 0 4
[junit4:junit4]   2> 5153 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10002 (1417531286602383360)]} 0 1
[junit4:junit4]   2> 5154 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[2 (1417531286602383361)]} 0 2
[junit4:junit4]   2> 5157 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[2 (1417531286605529089)]} 0 1
[junit4:junit4]   2> 5157 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10002 (1417531286605529088)]} 0 1
[junit4:junit4]   2> 5159 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[1 (-1417531286608674816)]} 0 0
[junit4:junit4]   2> 5159 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10001 (-1417531286608674817)]} 0 0
[junit4:junit4]   2> 5161 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10001 (-1417531286610771968)]} 0 0
[junit4:junit4]   2> 5162 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[1 (-1417531286610771969)]} 0 1
[junit4:junit4]   2> 5164 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[3 (1417531286613917696)]} 0 1
[junit4:junit4]   2> 5164 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10003 (1417531286613917697)]} 0 1
[junit4:junit4]   2> 5167 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[3 (1417531286616014848)]} 0 1
[junit4:junit4]   2> 5167 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10003 (1417531286616014849)]} 0 1
[junit4:junit4]   2> 5171 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10004 (1417531286619160576)]} 0 2
[junit4:junit4]   2> 5171 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[4 (1417531286621257728)]} 0 2
[junit4:junit4]   2> 5173 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[4 (1417531286623354880)]} 0 0
[junit4:junit4]   2> 5174 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10004 (1417531286623354881)]} 0 1
[junit4:junit4]   2> 5176 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10004 (-1417531286626500609)]} 0 1
[junit4:junit4]   2> 5176 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[4 (-1417531286626500608)]} 0 1
[junit4:junit4]   2> 5179 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[4 (-1417531286629646336)]} 0 1
[junit4:junit4]   2> 5179 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10004 (-1417531286629646337)]} 0 1
[junit4:junit4]   2> 5182 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[5 (1417531286631743488)]} 0 1
[junit4:junit4]   2> 5182 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10005 (1417531286631743489)]} 0 1
[junit4:junit4]   2> 5184 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[5 (1417531286634889216)]} 0 0
[junit4:junit4]   2> 5185 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10005 (1417531286634889217)]} 0 1
[junit4:junit4]   2> 5187 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[6 (1417531286636986368)]} 0 1
[junit4:junit4]   2> 5188 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10006 (1417531286638034944)]} 0 1
[junit4:junit4]   2> 5189 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[6 (1417531286640132096)]} 0 0
[junit4:junit4]   2> 5190 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10006 (1417531286641180672)]} 0 0
[junit4:junit4]   2> 5193 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10007 (1417531286643277824)]} 0 1
[junit4:junit4]   2> 5194 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[7 (1417531286642229248)]} 0 3
[junit4:junit4]   2> 5196 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10007 (1417531286647472128)]} 0 1
[junit4:junit4]   2> 5196 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[7 (1417531286647472129)]} 0 0
[junit4:junit4]   2> 5198 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10006 (-1417531286649569280)]} 0 0
[junit4:junit4]   2> 5199 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[6 (-1417531286650617856)]} 0 1
[junit4:junit4]   2> 5201 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10006 (-1417531286652715008)]} 0 1
[junit4:junit4]   2> 5201 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[6 (-1417531286652715009)]} 0 1
[junit4:junit4]   2> 5204 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[8 (1417531286654812161)]} 0 1
[junit4:junit4]   2> 5204 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10008 (1417531286654812160)]} 0 1
[junit4:junit4]   2> 5206 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[8 (1417531286657957888)]} 0 0
[junit4:junit4]   2> 5208 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10008 (1417531286657957889)]} 0 2
[junit4:junit4]   2> 5209 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[9 (1417531286661103616)]} 0 1
[junit4:junit4]   2> 5211 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10009 (1417531286662152192)]} 0 1
[junit4:junit4]   2> 5212 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[9 (1417531286663200768)]} 0 1
[junit4:junit4]   2> 5220 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10009 (1417531286665297920)]} 0 7
[junit4:junit4]   2> 5223 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10 (1417531286674735104)]} 0 1
[junit4:junit4]   2> 5223 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10010 (1417531286675783680)]} 0 0
[junit4:junit4]   2> 5225 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10 (1417531286677880832)]} 0 1
[junit4:junit4]   2> 5226 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10010 (1417531286677880833)]} 0 1
[junit4:junit4]   2> 5227 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[11 (1417531286679977984)]} 0 0
[junit4:junit4]   2> 5228 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10011 (1417531286681026560)]} 0 1
[junit4:junit4]   2> 5230 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[11 (1417531286683123712)]} 0 1
[junit4:junit4]   2> 5230 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10011 (1417531286683123713)]} 0 0
[junit4:junit4]   2> 5233 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[12 (1417531286685220864)]} 0 1
[junit4:junit4]   2> 5233 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10012 (1417531286685220865)]} 0 1
[junit4:junit4]   2> 5235 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10012 (1417531286688366592)]} 0 0
[junit4:junit4]   2> 5236 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[12 (1417531286688366593)]} 0 1
[junit4:junit4]   2> 5237 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10007 (-1417531286690463744)]} 0 0
[junit4:junit4]   2> 5238 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[7 (-1417531286691512320)]} 0 0
[junit4:junit4]   2> 5240 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10007 (-1417531286693609472)]} 0 1
[junit4:junit4]   2> 5240 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[7 (-1417531286693609473)]} 0 0
[junit4:junit4]   2> 5242 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10013 (1417531286695706624)]} 0 1
[junit4:junit4]   2> 5242 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[13 (1417531286695706625)]} 0 0
[junit4:junit4]   2> 5245 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10013 (1417531286697803776)]} 0 1
[junit4:junit4]   2> 5245 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[13 (1417531286697803777)]} 0 1
[junit4:junit4]   2> 5247 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10014 (1417531286700949504)]} 0 0
[junit4:junit4]   2> 5250 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[14 (1417531286700949505)]} 0 3
[junit4:junit4]   2> 5252 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10014 (1417531286703046656)]} 0 3
[junit4:junit4]   2> 5253 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[14 (1417531286707240960)]} 0 1
[junit4:junit4]   2> 5255 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10011 (-1417531286709338112)]} 0 0
[junit4:junit4]   2> 5255 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[11 (-1417531286709338113)]} 0 0
[junit4:junit4]   2> 5257 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10011 (-1417531286711435264)]} 0 0
[junit4:junit4]   2> 5258 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[11 (-1417531286711435265)]} 0 1
[junit4:junit4]   2> 5260 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10015 (1417531286713532416)]} 0 1
[junit4:junit4]   2> 5260 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[15 (1417531286713532417)]} 0 1
[junit4:junit4]   2> 5263 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[15 (1417531286716678145)]} 0 1
[junit4:junit4]   2> 5266 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[13 (-1417531286720872448)]} 0 1
[junit4:junit4]   2> 5267 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10015 (1417531286716678144)]} 0 5
[junit4:junit4]   2> 5268 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[13 (-1417531286722969600)]} 0 0
[junit4:junit4]   2> 5269 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10013 (-1417531286724018176)]} 0 0
[junit4:junit4]   2> 5271 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[16 (1417531286725066752)]} 0 1
[junit4:junit4]   2> 5273 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10013 (-1417531286728212480)]} 0 2
[junit4:junit4]   2> 5275 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[16 (1417531286729261056)]} 0 1
[junit4:junit4]   2> 5275 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10016 (1417531286730309632)]} 0 0
[junit4:junit4]   2> 5278 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[15 (-1417531286732406784)]} 0 1
[junit4:junit4]   2> 5278 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10016 (1417531286732406784)]} 0 1
[junit4:junit4]   2> 5280 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[15 (-1417531286735552512)]} 0 0
[junit4:junit4]   2> 5280 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10015 (-1417531286735552512)]} 0 0
[junit4:junit4]   2> 5282 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10015 (-1417531286737649664)]} 0 0
[junit4:junit4]   2> 5282 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[17 (1417531286737649664)]} 0 0
[junit4:junit4]   2> 5285 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[17 (1417531286739746816)]} 0 1
[junit4:junit4]   2> 5285 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10017 (1417531286739746816)]} 0 1
[junit4:junit4]   2> 5287 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[16 (-1417531286742892544)]} 0 0
[junit4:junit4]   2> 5287 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10017 (1417531286742892544)]} 0 0
[junit4:junit4]   2> 5290 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10016 (-1417531286744989696)]} 0 1
[junit4:junit4]   2> 5289 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[16 (-1417531286744989696)]} 0 0
[junit4:junit4]   2> 5291 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10016 (-1417531286747086848)]} 0 0
[junit4:junit4]   2> 5292 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[18 (1417531286748135424)]} 0 0
[junit4:junit4]   2> 5294 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10018 (1417531286749184000)]} 0 1
[junit4:junit4]   2> 5295 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[18 (1417531286750232576)]} 0 1
[junit4:junit4]   2> 5296 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10018 (1417531286752329728)]} 0 0
[junit4:junit4]   2> 5297 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[17 (-1417531286753378304)]} 0 1
[junit4:junit4]   2> 5298 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10017 (-1417531286754426880)]} 0 0
[junit4:junit4]   2> 5299 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[17 (-1417531286755475456)]} 0 0
[junit4:junit4]   2> 5300 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10017 (-1417531286756524032)]} 0 0
[junit4:junit4]   2> 5301 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[19 (1417531286757572608)]} 0 0
[junit4:junit4]   2> 5304 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[19 (1417531286760718336)]} 0 1
[junit4:junit4]   2> 5305 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10019 (1417531286758621184)]} 0 3
[junit4:junit4]   2> 5307 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[20 (1417531286762815488)]} 0 1
[junit4:junit4]   2> 5308 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10019 (1417531286763864064)]} 0 1
[junit4:junit4]   2> 5309 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[20 (1417531286765961216)]} 0 0
[junit4:junit4]   2> 5310 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10020 (1417531286767009792)]} 0 0
[junit4:junit4]   2> 5314 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[21 (1417531286771204096)]} 0 0
[junit4:junit4]   2> 5316 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10020 (1417531286772252672)]} 0 1
[junit4:junit4]   2> 5317 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[21 (1417531286773301248)]} 0 1
[junit4:junit4]   2> 5319 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[18 (-1417531286776446976)]} 0 0
[junit4:junit4]   2> 5319 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10021 (1417531286775398400)]} 0 1
[junit4:junit4]   2> 5321 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[18 (-1417531286778544128)]} 0 0
[junit4:junit4]   2> 5322 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10021 (1417531286778544129)]} 0 1
[junit4:junit4]   2> 5324 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10018 (-1417531286781689856)]} 0 0
[junit4:junit4]   2> 5326 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[22 (1417531286780641280)]} 0 3
[junit4:junit4]   2> 5327 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10018 (-1417531286784835584)]} 0 1
[junit4:junit4]   2> 5329 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[22 (1417531286785884160)]} 0 1
[junit4:junit4]   2> 5329 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10022 (1417531286786932736)]} 0 0
[junit4:junit4]   2> 5331 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[23 (1417531286789029888)]} 0 0
[junit4:junit4]   2> 5334 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10022 (1417531286789029888)]} 0 3
[junit4:junit4]   2> 5334 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[23 (1417531286792175616)]} 0 1
[junit4:junit4]   2> 5336 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[21 (-1417531286794272769)]} 0 0
[junit4:junit4]   2> 5337 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10023 (1417531286794272768)]} 0 1
[junit4:junit4]   2> 5339 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[21 (-1417531286796369920)]} 0 1
[junit4:junit4]   2> 5340 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10023 (1417531286797418496)]} 0 1
[junit4:junit4]   2> 5341 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[24 (1417531286799515648)]} 0 1
[junit4:junit4]   2> 5342 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10021 (-1417531286800564224)]} 0 1
[junit4:junit4]   2> 5344 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[24 (1417531286801612800)]} 0 1
[junit4:junit4]   2> 5344 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10021 (-1417531286802661376)]} 0 0
[junit4:junit4]   2> 5347 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10024 (1417531286804758529)]} 0 1
[junit4:junit4]   2> 5347 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[25 (1417531286804758528)]} 0 1
[junit4:junit4]   2> 5350 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10024 (1417531286807904256)]} 0 1
[junit4:junit4]   2> 5350 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[25 (1417531286807904257)]} 0 1
[junit4:junit4]   2> 5352 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[22 (-1417531286811049984)]} 0 0
[junit4:junit4]   2> 5352 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10025 (1417531286811049985)]} 0 0
[junit4:junit4]   2> 5354 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[22 (-1417531286813147136)]} 0 0
[junit4:junit4]   2> 5355 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10025 (1417531286813147137)]} 0 1
[junit4:junit4]   2> 5356 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[26 (1417531286815244288)]} 0 0
[junit4:junit4]   2> 5357 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10022 (-1417531286816292864)]} 0 1
[junit4:junit4]   2> 5360 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[26 (1417531286817341440)]} 0 2
[junit4:junit4]   2> 5360 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10022 (-1417531286819438592)]} 0 0
[junit4:junit4]   2> 5362 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[25 (-1417531286821535744)]} 0 0
[junit4:junit4]   2> 5364 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[25 (-1417531286823632896)]} 0 0
[junit4:junit4]   2> 5365 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10026 (1417531286821535745)]} 0 3
[junit4:junit4]   2> 5366 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[27 (1417531286825730048)]} 0 0
[junit4:junit4]   2> 5367 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10026 (1417531286825730048)]} 0 1
[junit4:junit4]   2> 5369 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10025 (-1417531286828875776)]} 0 0
[junit4:junit4]   2> 5369 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[27 (1417531286827827200)]} 0 1
[junit4:junit4]   2> 5371 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10025 (-1417531286830972928)]} 0 0
[junit4:junit4]   2> 5371 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[28 (1417531286830972928)]} 0 0
[junit4:junit4]   2> 5373 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10027 (1417531286833070080)]} 0 0
[junit4:junit4]   2> 5374 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[28 (1417531286833070080)]} 0 1
[junit4:junit4]   2> 5375 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10027 (1417531286835167232)]} 0 0
[junit4:junit4]   2> 5375 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[27 (-1417531286835167232)]} 0 0
[junit4:junit4]   2> 5377 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[27 (-1417531286837264384)]} 0 0
[junit4:junit4]   2> 5378 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10028 (1417531286837264384)]} 0 1
[junit4:junit4]   2> 5380 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[29 (1417531286839361536)]} 0 1
[junit4:junit4]   2> 5380 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10028 (1417531286839361536)]} 0 1
[junit4:junit4]   2> 5382 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[10027 (-1417531286842507264)]} 0 0
[junit4:junit4]   2> 5382 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[29 (1417531286841458688)]} 0 1
[junit4:junit4]   2> 5384 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10027 (-1417531286844604416)]} 0 1
[junit4:junit4]   2> 5384 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[28 (-1417531286844604416)]} 0 0
[junit4:junit4]   2> 5386 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[28 (-1417531286846701568)]} 0 1
[junit4:junit4]   2> 5386 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10029 (1417531286845652992)]} 0 1
[junit4:junit4]   2> 5388 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[30 (1417531286847750144)]} 0 1
[junit4:junit4]   2> 5390 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10029 (1417531286848798720)]} 0 2
[junit4:junit4]   2> 5390 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[30 (1417531286850895872)]} 0 0
[junit4:junit4]   2> 5392 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[10028 (-1417531286852993024)]} 0 0
[junit4:junit4]   2> 5392 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[30 (-1417531286852993025)]} 0 0
[junit4:junit4]   2> 5394 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10028 (-1417531286855090176)]} 0 0
[junit4:junit4]   2> 5394 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[30 (-1417531286855090177)]} 0 0
[junit4:junit4]   2> 5396 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10030 (1417531286857187328)]} 0 0
[junit4:junit4]   2> 5397 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[31 (1417531286857187329)]} 0 1
[junit4:junit4]   2> 5400 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[31 (1417531286860333057)]} 0 1
[junit4:junit4]   2> 5401 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10030 (1417531286860333056)]} 0 3
[junit4:junit4]   2> 5402 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[32 (1417531286863478784)]} 0 0
[junit4:junit4]   2> 5403 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10030 (-1417531286864527360)]} 0 0
[junit4:junit4]   2> 5405 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[32 (1417531286865575936)]} 0 1
[junit4:junit4]   2> 5405 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10030 (-1417531286866624512)]} 0 0
[junit4:junit4]   2> 5410 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[33 (1417531286870818816)]} 0 2
[junit4:junit4]   2> 5412 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10031 (1417531286870818817)]} 0 4
[junit4:junit4]   2> 5413 T1335 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5413 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[33 (1417531286873964544)]} 0 1
[junit4:junit4]   2> 5413 T1335 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37516_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37516/solr"}
[junit4:junit4]   2> 5414 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10031 (1417531286876061696)]} 0 0
[junit4:junit4]   2> 5416 T1339 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5416 T1334 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5416 T1350 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5416 T1362 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5417 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10032 (1417531286878158848)]} 0 1
[junit4:junit4]   2> 5419 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[34 (1417531286877110272)]} 0 4
[junit4:junit4]   2>  C82_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:37516_solr, base_url=http://127.0.0.1:37516/solr}
[junit4:junit4]   2> 5437 T1357 C82 P37516 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2
[junit4:junit4]   2> 5438 T1357 C82 P37516 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ec93cf lockFactory=org.apache.lucene.store.NativeFSLockFactory@f7be6a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5439 T1357 C82 P37516 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5440 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 5441 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 5441 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[34 (1417531286883401729)]} 0 20
[junit4:junit4]   2> 5441 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10032 (1417531286883401728)]} 0 22
[junit4:junit4]   2> 5444 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[33 (-1417531286907518976)]} 0 1
[junit4:junit4]   2> 5444 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10033 (1417531286907518977)]} 0 1
[junit4:junit4]   2> 5448 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5449 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[33 (-1417531286909616128)]} 0 3
[junit4:junit4]   2> 5449 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5450 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10033 (1417531286909616129)]} 0 4
[junit4:junit4]   2> 5452 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10034 (1417531286914859009)]} 0 1
[junit4:junit4]   2> 5452 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[35 (1417531286914859008)]} 0 1
[junit4:junit4]   2> 5457 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5458 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[35 (1417531286918004737)]} 0 4
[junit4:junit4]   2> 5458 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5458 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10034 (1417531286918004736)]} 0 4
[junit4:junit4]   2> 5460 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10033 (-1417531286924296193)]} 0 0
[junit4:junit4]   2> 5460 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[36 (1417531286924296192)]} 0 0
[junit4:junit4]   2> 5464 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5464 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10033 (-1417531286926393344)]} 0 2
[junit4:junit4]   2> 5465 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5465 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[36 (1417531286926393345)]} 0 3
[junit4:junit4]   2> 5466 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10035 (1417531286930587648)]} 0 0
[junit4:junit4]   2> 5468 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[37 (1417531286931636224)]} 0 1
[junit4:junit4]   2> 5471 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5471 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10035 (1417531286932684800)]} 0 3
[junit4:junit4]   2> 5472 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5473 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[37 (1417531286934781952)]} 0 4
[junit4:junit4]   2> 5473 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10036 (1417531286937927680)]} 0 0
[junit4:junit4]   2> 5475 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[34 (-1417531286940024832)]} 0 0
[junit4:junit4]   2> 5478 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5478 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10036 (1417531286940024832)]} 0 3
[junit4:junit4]   2> 5479 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5479 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[34 (-1417531286942121984)]} 0 2
[junit4:junit4]   2> 5481 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10037 (1417531286945267712)]} 0 1
[junit4:junit4]   2> 5482 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[38 (1417531286946316288)]} 0 1
[junit4:junit4]   2> 5487 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 5488 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10037 (1417531286947364864)]} 0 6
[junit4:junit4]   2> 5490 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10034 (-1417531286955753472)]} 0 0
[junit4:junit4]   2> 5490 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5490 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[38 (1417531286949462016)]} 0 6
[junit4:junit4]   2> 5493 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[39 (1417531286957850624)]} 0 1
[junit4:junit4]   2> 5493 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5494 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10034 (-1417531286957850624)]} 0 2
[junit4:junit4]   2> 5498 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5498 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[39 (1417531286960996352)]} 0 3
[junit4:junit4]   2> 5499 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10038 (1417531286962044928)]} 0 3
[junit4:junit4]   2> 5501 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[40 (1417531286967287808)]} 0 1
[junit4:junit4]   2> 5504 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5504 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10038 (1417531286967287808)]} 0 3
[junit4:junit4]   2> 5506 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5506 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[40 (1417531286969384960)]} 0 3
[junit4:junit4]   2> 5507 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10039 (1417531286972530688)]} 0 1
[junit4:junit4]   2> 5508 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[37 (-1417531286974627840)]} 0 0
[junit4:junit4]   2> 5512 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5512 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5512 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[37 (-1417531286976724992)]} 0 2
[junit4:junit4]   2> 5513 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10039 (1417531286975676416)]} 0 4
[junit4:junit4]   2> 5515 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[41 (1417531286980919296)]} 0 1
[junit4:junit4]   2> 5515 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10040 (1417531286981967872)]} 0 1
[junit4:junit4]   2> 5519 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5520 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[41 (1417531286984065024)]} 0 3
[junit4:junit4]   2> 5520 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5520 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10040 (1417531286984065025)]} 0 3
[junit4:junit4]   2> 5523 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[10037 (-1417531286989307905)]} 0 0
[junit4:junit4]   2> 5523 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[42 (1417531286989307904)]} 0 0
[junit4:junit4]   2> 5526 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5527 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10037 (-1417531286991405056)]} 0 3
[junit4:junit4]   2> 5528 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5528 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[42 (1417531286992453632)]} 0 3
[junit4:junit4]   2> 5530 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[38 (-1417531286997696512)]} 0 0
[junit4:junit4]   2> 5534 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5535 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[38 (-1417531286999793664)]} 0 3
[junit4:junit4]   2> 5586 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[43 (1417531287005036544)]} 0 49
[junit4:junit4]   2> 5591 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5593 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[43 (1417531287058513920)]} 0 5
[junit4:junit4]   2> 5595 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[40 (-1417531287065853952)]} 0 0
[junit4:junit4]   2> 5599 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5600 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[40 (-1417531287067951104)]} 0 3
[junit4:junit4]   2> 5602 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[44 (1417531287073193984)]} 0 0
[junit4:junit4]   2> 5610 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 5611 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[44 (1417531287075291136)]} 0 7
[junit4:junit4]   2> 5613 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[45 (1417531287084728320)]} 0 0
[junit4:junit4]   2> 5618 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5619 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[45 (1417531287087874048)]} 0 4
[junit4:junit4]   2> 5622 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[46 (1417531287093116928)]} 0 1
[junit4:junit4]   2> 5626 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5626 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[46 (1417531287095214080)]} 0 3
[junit4:junit4]   2> 5629 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[47 (1417531287100456960)]} 0 1
[junit4:junit4]   2> 5633 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5634 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[47 (1417531287103602688)]} 0 4
[junit4:junit4]   2> 5636 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[44 (-1417531287108845568)]} 0 0
[junit4:junit4]   2> 5640 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5640 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[44 (-1417531287110942720)]} 0 2
[junit4:junit4]   2> 5689 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[48 (1417531287115137024)]} 0 47
[junit4:junit4]   2> 5694 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10041 (1417531286996647936)]} 0 165
[junit4:junit4]   2> 5695 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5695 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[48 (1417531287166517248)]} 0 4
[junit4:junit4]   2> 5702 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[49 (1417531287173857280)]} 0 5
[junit4:junit4]   2> 5714 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5715 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5715 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10041 (1417531287171760128)]} 0 19
[junit4:junit4]   2> 5715 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[49 (1417531287180148736)]} 0 11
[junit4:junit4]   2> 5718 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[45 (-1417531287194828800)]} 0 1
[junit4:junit4]   2> 5720 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10042 (1417531287195877376)]} 0 1
[junit4:junit4]   2> 5722 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5722 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[45 (-1417531287196925952)]} 0 2
[junit4:junit4]   2> 5725 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5726 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[50 (1417531287202168832)]} 0 2
[junit4:junit4]   2> 5726 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10042 (1417531287199023104)]} 0 4
[junit4:junit4]   2> 5728 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10038 (-1417531287205314560)]} 0 0
[junit4:junit4]   2> 5731 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5732 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[50 (1417531287205314560)]} 0 4
[junit4:junit4]   2> 5732 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5733 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10038 (-1417531287207411712)]} 0 3
[junit4:junit4]   2> 5734 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[46 (-1417531287211606016)]} 0 0
[junit4:junit4]   2> 5736 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10043 (1417531287212654592)]} 0 1
[junit4:junit4]   2> 5738 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5739 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[46 (-1417531287213703168)]} 0 3
[junit4:junit4]   2> 5740 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5741 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10043 (1417531287215800320)]} 0 3
[junit4:junit4]   2> 5741 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[51 (1417531287218946048)]} 0 0
[junit4:junit4]   2> 5743 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10040 (-1417531287221043200)]} 0 0
[junit4:junit4]   2> 5747 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5747 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5748 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10040 (-1417531287223140352)]} 0 3
[junit4:junit4]   2> 5748 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[51 (1417531287221043200)]} 0 5
[junit4:junit4]   2> 5750 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10044 (1417531287228383232)]} 0 1
[junit4:junit4]   2> 5750 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[52 (1417531287228383233)]} 0 0
[junit4:junit4]   2> 5755 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5755 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5756 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10044 (1417531287230480384)]} 0 4
[junit4:junit4]   2> 5756 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[52 (1417531287230480385)]} 0 4
[junit4:junit4]   2> 5758 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10045 (1417531287236771840)]} 0 0
[junit4:junit4]   2> 5758 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[53 (1417531287236771841)]} 0 0
[junit4:junit4]   2> 5763 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5764 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5764 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[53 (1417531287239917568)]} 0 4
[junit4:junit4]   2> 5764 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10045 (1417531287239917569)]} 0 4
[junit4:junit4]   2> 5766 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[54 (1417531287245160448)]} 0 0
[junit4:junit4]   2> 5766 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10046 (1417531287245160449)]} 0 0
[junit4:junit4]   2> 5771 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5772 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5772 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10046 (1417531287247257601)]} 0 4
[junit4:junit4]   2> 5772 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[54 (1417531287247257600)]} 0 4
[junit4:junit4]   2> 5775 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10047 (1417531287253549056)]} 0 1
[junit4:junit4]   2> 5775 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[55 (1417531287253549057)]} 0 1
[junit4:junit4]   2> 5780 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5781 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[55 (1417531287256694784)]} 0 4
[junit4:junit4]   2> 5783 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5783 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[48 (-1417531287262986240)]} 0 0
[junit4:junit4]   2> 5783 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10047 (1417531287259840512)]} 0 3
[junit4:junit4]   2> 5785 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10044 (-1417531287265083392)]} 0 0
[junit4:junit4]   2> 5787 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5787 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[48 (-1417531287265083392)]} 0 2
[junit4:junit4]   2> 5789 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5789 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10044 (-1417531287267180544)]} 0 2
[junit4:junit4]   2> 5790 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[56 (1417531287269277696)]} 0 1
[junit4:junit4]   2> 5792 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10048 (1417531287271374848)]} 0 1
[junit4:junit4]   2> 5795 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5795 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[56 (1417531287272423424)]} 0 3
[junit4:junit4]   2> 5797 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[49 (-1417531287277666304)]} 0 0
[junit4:junit4]   2> 5800 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 5801 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10048 (1417531287274520576)]} 0 7
[junit4:junit4]   2> 5801 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5802 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[49 (-1417531287280812032)]} 0 3
[junit4:junit4]   2> 5805 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[57 (1417531287285006336)]} 0 1
[junit4:junit4]   2> 5806 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10049 (1417531287283957760)]} 0 3
[junit4:junit4]   2> 5810 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5811 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[57 (1417531287288152064)]} 0 4
[junit4:junit4]   2> 5813 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5813 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10049 (1417531287291297792)]} 0 3
[junit4:junit4]   2> 5814 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[58 (1417531287294443520)]} 0 1
[junit4:junit4]   2> 5816 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[10045 (-1417531287296540672)]} 0 1
[junit4:junit4]   2> 5819 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5819 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[58 (1417531287297589248)]} 0 3
[junit4:junit4]   2> 5819 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5820 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10045 (-1417531287299686400)]} 0 3
[junit4:junit4]   2> 5822 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[59 (1417531287303880704)]} 0 1
[junit4:junit4]   2> 5822 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10050 (1417531287303880705)]} 0 0
[junit4:junit4]   2> 5836 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 5836 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5837 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10050 (1417531287305977856)]} 0 13
[junit4:junit4]   2> 5837 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[59 (1417531287305977857)]} 0 13
[junit4:junit4]   2> 5839 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[50 (-1417531287321706496)]} 0 0
[junit4:junit4]   2> 5840 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10046 (-1417531287322755072)]} 0 0
[junit4:junit4]   2> 5843 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5844 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5844 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[50 (-1417531287323803648)]} 0 3
[junit4:junit4]   2> 5844 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10046 (-1417531287324852224)]} 0 2
[junit4:junit4]   2> 5847 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[60 (1417531287329046528)]} 0 1
[junit4:junit4]   2> 5847 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10051 (1417531287329046529)]} 0 1
[junit4:junit4]   2> 5852 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5853 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5853 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[60 (1417531287333240833)]} 0 4
[junit4:junit4]   2> 5853 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10051 (1417531287333240832)]} 0 4
[junit4:junit4]   2> 5856 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[51 (-1417531287338483712)]} 0 1
[junit4:junit4]   2> 5857 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10052 (1417531287339532288)]} 0 1
[junit4:junit4]   2> 5864 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5864 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10052 (1417531287344775168)]} 0 3
[junit4:junit4]   2> 5866 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5867 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10053 (1417531287350018048)]} 0 1
[junit4:junit4]   2> 5867 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[51 (-1417531287347920896)]} 0 4
[junit4:junit4]   2> 5870 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[61 (1417531287353163776)]} 0 1
[junit4:junit4]   2> 5874 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5875 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10053 (1417531287353163776)]} 0 6
[junit4:junit4]   2> 5877 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10054 (1417531287361552384)]} 0 0
[junit4:junit4]   2> 5877 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 5878 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[61 (1417531287356309504)]} 0 6
[junit4:junit4]   2> 5880 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[53 (-1417531287364698112)]} 0 0
[junit4:junit4]   2> 5883 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5883 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10054 (1417531287363649536)]} 0 4
[junit4:junit4]   2> 5884 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5884 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[53 (-1417531287366795264)]} 0 2
[junit4:junit4]   2> 5886 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10055 (1417531287369940992)]} 0 1
[junit4:junit4]   2> 5889 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[62 (1417531287370989568)]} 0 3
[junit4:junit4]   2> 5890 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5891 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10055 (1417531287373086720)]} 0 3
[junit4:junit4]   2> 5893 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[10048 (-1417531287378329600)]} 0 0
[junit4:junit4]   2> 5893 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5894 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[62 (1417531287376232448)]} 0 4
[junit4:junit4]   2> 5896 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[54 (-1417531287381475328)]} 0 0
[junit4:junit4]   2> 5897 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5897 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10048 (-1417531287380426752)]} 0 2
[junit4:junit4]   2> 5900 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5900 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[54 (-1417531287383572480)]} 0 2
[junit4:junit4]   2> 5901 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10056 (1417531287384621056)]} 0 1
[junit4:junit4]   2> 5903 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[63 (1417531287387766784)]} 0 1
[junit4:junit4]   2> 5905 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5906 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10056 (1417531287388815360)]} 0 4
[junit4:junit4]   2> 5908 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5908 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10049 (-1417531287394058240)]} 0 0
[junit4:junit4]   2> 5908 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[63 (1417531287390912512)]} 0 3
[junit4:junit4]   2> 5910 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[55 (-1417531287396155392)]} 0 0
[junit4:junit4]   2> 5912 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5912 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10049 (-1417531287396155392)]} 0 2
[junit4:junit4]   2> 5914 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5915 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[55 (-1417531287399301120)]} 0 3
[junit4:junit4]   2> 5915 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10057 (1417531287400349696)]} 0 1
[junit4:junit4]   2> 5917 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[64 (1417531287403495424)]} 0 0
[junit4:junit4]   2> 5920 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5921 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10057 (1417531287403495424)]} 0 4
[junit4:junit4]   2> 5922 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5923 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10058 (1417531287409786880)]} 0 0
[junit4:junit4]   2> 5924 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[64 (1417531287405592576)]} 0 5
[junit4:junit4]   2> 5926 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[59 (-1417531287412932608)]} 0 0
[junit4:junit4]   2> 5930 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5931 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[59 (-1417531287415029760)]} 0 3
[junit4:junit4]   2> 5931 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5931 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10058 (1417531287412932608)]} 0 6
[junit4:junit4]   2> 5933 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[65 (1417531287420272640)]} 0 0
[junit4:junit4]   2> 5934 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10059 (1417531287420272641)]} 0 1
[junit4:junit4]   2> 5938 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5938 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[65 (1417531287422369792)]} 0 3
[junit4:junit4]   2> 5941 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[66 (1417531287427612672)]} 0 1
[junit4:junit4]   2> 5942 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 5942 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10059 (1417531287423418368)]} 0 7
[junit4:junit4]   2> 5944 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10050 (-1417531287431806976)]} 0 0
[junit4:junit4]   2> 5946 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5946 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[66 (1417531287430758400)]} 0 3
[junit4:junit4]   2> 5948 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5948 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10050 (-1417531287433904128)]} 0 2
[junit4:junit4]   2> 5948 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[67 (1417531287436001280)]} 0 0
[junit4:junit4]   2> 5951 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10060 (1417531287438098432)]} 0 1
[junit4:junit4]   2> 5953 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5954 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[67 (1417531287438098432)]} 0 4
[junit4:junit4]   2> 5956 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[62 (-1417531287444389888)]} 0 1
[junit4:junit4]   2> 5956 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5956 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10060 (1417531287441244160)]} 0 3
[junit4:junit4]   2> 5958 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10051 (-1417531287446487040)]} 0 0
[junit4:junit4]   2> 5959 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5960 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[62 (-1417531287446487040)]} 0 2
[junit4:junit4]   2> 5962 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5962 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10051 (-1417531287448584192)]} 0 2
[junit4:junit4]   2> 5964 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[68 (1417531287450681344)]} 0 3
[junit4:junit4]   2> 5965 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10061 (1417531287452778496)]} 0 1
[junit4:junit4]   2> 5969 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5969 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[68 (1417531287454875648)]} 0 3
[junit4:junit4]   2> 5970 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5970 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10061 (1417531287455924224)]} 0 3
[junit4:junit4]   2> 5972 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[63 (-1417531287461167104)]} 0 1
[junit4:junit4]   2> 5972 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10053 (-1417531287461167105)]} 0 0
[junit4:junit4]   2> 5976 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5976 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5976 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[63 (-1417531287463264256)]} 0 2
[junit4:junit4]   2> 5977 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10053 (-1417531287463264257)]} 0 3
[junit4:junit4]   2> 5979 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[69 (1417531287467458560)]} 0 1
[junit4:junit4]   2> 5982 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10062 (1417531287468507136)]} 0 4
[junit4:junit4]   2> 5984 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5985 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[69 (1417531287470604288)]} 0 4
[junit4:junit4]   2> 5987 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5987 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[70 (1417531287475847168)]} 0 1
[junit4:junit4]   2> 5987 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10062 (1417531287473750016)]} 0 3
[junit4:junit4]   2> 5989 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10054 (-1417531287478992896)]} 0 0
[junit4:junit4]   2> 5992 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5992 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[70 (1417531287478992896)]} 0 3
[junit4:junit4]   2> 5993 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5994 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10054 (-1417531287481090048)]} 0 3
[junit4:junit4]   2> 5994 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[66 (-1417531287484235776)]} 0 0
[junit4:junit4]   2> 5996 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10063 (1417531287485284352)]} 0 1
[junit4:junit4]   2> 5998 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5999 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[66 (-1417531287486332928)]} 0 3
[junit4:junit4]   2> 6001 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6001 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[71 (1417531287490527232)]} 0 1
[junit4:junit4]   2> 6002 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10063 (1417531287488430080)]} 0 4
[junit4:junit4]   2> 6004 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10055 (-1417531287494721536)]} 0 1
[junit4:junit4]   2> 6007 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6008 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6008 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10055 (-1417531287496818688)]} 0 2
[junit4:junit4]   2> 6008 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[71 (1417531287493672960)]} 0 5
[junit4:junit4]   2> 6011 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10064 (1417531287501012992)]} 0 1
[junit4:junit4]   2> 6011 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[72 (1417531287501012993)]} 0 1
[junit4:junit4]   2> 6015 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6017 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6018 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10064 (1417531287504158720)]} 0 6
[junit4:junit4]   2> 6018 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[72 (1417531287504158721)]} 0 5
[junit4:junit4]   2> 6020 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[67 (-1417531287511498752)]} 0 0
[junit4:junit4]   2> 6020 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10059 (-1417531287511498753)]} 0 0
[junit4:junit4]   2> 6024 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6024 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6024 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[67 (-1417531287513595904)]} 0 2
[junit4:junit4]   2> 6024 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10059 (-1417531287513595905)]} 0 2
[junit4:junit4]   2> 6026 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[73 (1417531287517790208)]} 0 0
[junit4:junit4]   2> 6027 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10065 (1417531287517790209)]} 0 1
[junit4:junit4]   2> 6031 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6031 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6032 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10065 (1417531287520935937)]} 0 3
[junit4:junit4]   2> 6032 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[73 (1417531287520935936)]} 0 4
[junit4:junit4]   2> 6034 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10066 (1417531287526178816)]} 0 0
[junit4:junit4]   2> 6034 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[69 (-1417531287526178817)]} 0 0
[junit4:junit4]   2> 6038 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6039 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[69 (-1417531287529324544)]} 0 3
[junit4:junit4]   2> 6039 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6040 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10066 (1417531287528275968)]} 0 4
[junit4:junit4]   2> 6041 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[74 (1417531287533518848)]} 0 0
[junit4:junit4]   2> 6042 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10067 (1417531287534567424)]} 0 0
[junit4:junit4]   2> 6047 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6047 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6047 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[74 (1417531287536664576)]} 0 3
[junit4:junit4]   2> 6048 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10067 (1417531287536664577)]} 0 4
[junit4:junit4]   2> 6050 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[74 (-1417531287542956032)]} 0 1
[junit4:junit4]   2> 6050 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10062 (-1417531287542956033)]} 0 0
[junit4:junit4]   2> 6054 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6054 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6054 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10062 (-1417531287545053185)]} 0 2
[junit4:junit4]   2> 6055 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[74 (-1417531287545053184)]} 0 3
[junit4:junit4]   2> 6057 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[75 (1417531287550296064)]} 0 1
[junit4:junit4]   2> 6057 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10068 (1417531287550296065)]} 0 0
[junit4:junit4]   2> 6062 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6062 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6063 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[75 (1417531287552393216)]} 0 4
[junit4:junit4]   2> 6063 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10068 (1417531287553441792)]} 0 4
[junit4:junit4]   2> 6065 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[75 (-1417531287558684672)]} 0 1
[junit4:junit4]   2> 6065 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10063 (-1417531287558684673)]} 0 0
[junit4:junit4]   2> 6069 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6069 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6070 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[75 (-1417531287560781824)]} 0 3
[junit4:junit4]   2> 6070 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10063 (-1417531287560781825)]} 0 3
[junit4:junit4]   2> 6072 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10069 (1417531287566024705)]} 0 0
[junit4:junit4]   2> 6072 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[76 (1417531287566024704)]} 0 0
[junit4:junit4]   2> 6077 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6078 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[76 (1417531287568121857)]} 0 4
[junit4:junit4]   2> 6081 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[77 (1417531287574413312)]} 0 1
[junit4:junit4]   2> 6087 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6088 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[77 (1417531287577559040)]} 0 5
[junit4:junit4]   2> 6092 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[78 (1417531287584899072)]} 0 2
[junit4:junit4]   2> 6097 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6098 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[78 (1417531287590141952)]} 0 4
[junit4:junit4]   2> 6102 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[79 (1417531287596433408)]} 0 1
[junit4:junit4]   2> 6109 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6109 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[79 (1417531287601676288)]} 0 3
[junit4:junit4]   2> 6112 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[80 (1417531287606919168)]} 0 1
[junit4:junit4]   2> 6116 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6117 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[80 (1417531287610064896)]} 0 3
[junit4:junit4]   2> 6119 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[81 (1417531287615307776)]} 0 1
[junit4:junit4]   2> 6123 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6124 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[81 (1417531287617404928)]} 0 3
[junit4:junit4]   2> 6126 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[78 (-1417531287622647808)]} 0 0
[junit4:junit4]   2> 6129 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6130 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[78 (-1417531287624744960)]} 0 2
[junit4:junit4]   2> 6132 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[82 (1417531287628939264)]} 0 0
[junit4:junit4]   2> 6140 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=64 
[junit4:junit4]   2> 6141 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10069 (1417531287568121856)]} 0 67
[junit4:junit4]   2> 6144 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10070 (1417531287640473600)]} 0 1
[junit4:junit4]   2> 6149 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6150 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10070 (1417531287643619328)]} 0 5
[junit4:junit4]   2> 6151 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6151 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[82 (1417531287631036416)]} 0 17
[junit4:junit4]   2> 6152 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10066 (-1417531287649910784)]} 0 0
[junit4:junit4]   2> 6154 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[83 (1417531287650959360)]} 0 1
[junit4:junit4]   2> 6156 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6157 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[10066 (-1417531287653056512)]} 0 3
[junit4:junit4]   2> 6202 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6203 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[83 (1417531287654105088)]} 0 47
[junit4:junit4]   2> 6217 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[84 (1417531287705485312)]} 0 12
[junit4:junit4]   2> 6225 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6225 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[84 (1417531287721213952)]} 0 5
[junit4:junit4]   2> 6228 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[85 (1417531287729602560)]} 0 1
[junit4:junit4]   2> 6230 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10071 (1417531287657250816)]} 0 71
[junit4:junit4]   2> 6236 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6236 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6237 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10071 (1417531287734845440)]} 0 5
[junit4:junit4]   2> 6237 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[85 (1417531287734845441)]} 0 4
[junit4:junit4]   2> 6239 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[80 (-1417531287741136896)]} 0 0
[junit4:junit4]   2> 6240 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10072 (1417531287741136897)]} 0 1
[junit4:junit4]   2> 6244 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6244 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[80 (-1417531287744282624)]} 0 2
[junit4:junit4]   2> 6245 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6246 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10072 (1417531287744282625)]} 0 4
[junit4:junit4]   2> 6248 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[86 (1417531287749525504)]} 0 1
[junit4:junit4]   2> 6249 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[10067 (-1417531287751622656)]} 0 0
[junit4:junit4]   2> 6254 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6254 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[10067 (-1417531287754768384)]} 0 3
[junit4:junit4]   2> 6254 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6255 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[86 (1417531287752671232)]} 0 5
[junit4:junit4]   2> 6257 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10073 (1417531287760011264)]} 0 0
[junit4:junit4]   2> 6261 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[87 (1417531287761059840)]} 0 3
[junit4:junit4]   2> 6262 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6263 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10073 (1417531287763156992)]} 0 4
[junit4:junit4]   2> 6265 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10069 (-1417531287768399872)]} 0 0
[junit4:junit4]   2> 6266 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6266 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[87 (1417531287766302720)]} 0 3
[junit4:junit4]   2> 6269 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6269 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[88 (1417531287771545600)]} 0 1
[junit4:junit4]   2> 6269 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[10069 (-1417531287770497024)]} 0 2
[junit4:junit4]   2> 6272 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10074 (1417531287775739904)]} 0 0
[junit4:junit4]   2> 6274 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6275 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[88 (1417531287775739904)]} 0 3
[junit4:junit4]   2> 6277 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6278 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10074 (1417531287778885632)]} 0 3
[junit4:junit4]   2> 6278 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[89 (1417531287782031360)]} 0 0
[junit4:junit4]   2> 6280 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10074 (-1417531287784128512)]} 0 0
[junit4:junit4]   2> 6283 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6283 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[89 (1417531287784128512)]} 0 3
[junit4:junit4]   2> 6284 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6284 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[10074 (-1417531287786225664)]} 0 2
[junit4:junit4]   2> 6285 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[82 (-1417531287789371392)]} 0 0
[junit4:junit4]   2> 6287 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10075 (1417531287790419968)]} 0 1
[junit4:junit4]   2> 6289 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6290 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[82 (-1417531287791468544)]} 0 3
[junit4:junit4]   2> 6292 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[90 (1417531287796711424)]} 0 1
[junit4:junit4]   2> 6294 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6294 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10075 (1417531287792517120)]} 0 6
[junit4:junit4]   2> 6296 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10075 (-1417531287800905728)]} 0 0
[junit4:junit4]   2> 6300 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6301 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6301 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[10075 (-1417531287803002880)]} 0 3
[junit4:junit4]   2> 6301 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[90 (1417531287798808576)]} 0 7
[junit4:junit4]   2> 6304 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10076 (1417531287808245760)]} 0 1
[junit4:junit4]   2> 6304 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[91 (1417531287808245761)]} 0 1
[junit4:junit4]   2> 6308 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6309 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6309 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10076 (1417531287811391488)]} 0 3
[junit4:junit4]   2> 6309 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[91 (1417531287811391489)]} 0 3
[junit4:junit4]   2> 6311 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[85 (-1417531287816634369)]} 0 0
[junit4:junit4]   2> 6312 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10077 (1417531287816634368)]} 0 1
[junit4:junit4]   2> 6315 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6316 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[85 (-1417531287818731520)]} 0 3
[junit4:junit4]   2> 6317 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6317 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10077 (1417531287819780096)]} 0 3
[junit4:junit4]   2> 6319 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[92 (1417531287825022976)]} 0 0
[junit4:junit4]   2> 6320 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10078 (1417531287825022977)]} 0 1
[junit4:junit4]   2> 6325 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6325 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6325 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10078 (1417531287828168705)]} 0 3
[junit4:junit4]   2> 6326 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[92 (1417531287828168704)]} 0 5
[junit4:junit4]   2> 6329 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[93 (1417531287834460161)]} 0 1
[junit4:junit4]   2> 6331 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10079 (1417531287834460160)]} 0 3
[junit4:junit4]   2> 6334 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6334 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[93 (1417531287837605888)]} 0 3
[junit4:junit4]   2> 6336 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6336 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10079 (1417531287839703040)]} 0 3
[junit4:junit4]   2> 6336 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[86 (-1417531287842848768)]} 0 0
[junit4:junit4]   2> 6339 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10080 (1417531287844945920)]} 0 1
[junit4:junit4]   2> 6340 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6340 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[86 (-1417531287844945920)]} 0 2
[junit4:junit4]   2> 6343 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[94 (1417531287849140224)]} 0 1
[junit4:junit4]   2> 6345 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 6346 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10080 (1417531287848091648)]} 0 5
[junit4:junit4]   2> 6348 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10081 (1417531287855431680)]} 0 0
[junit4:junit4]   2> 6351 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6352 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[94 (1417531287853334528)]} 0 6
[junit4:junit4]   2> 6353 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6353 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10081 (1417531287857528832)]} 0 3
[junit4:junit4]   2> 6354 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[95 (1417531287861723136)]} 0 0
[junit4:junit4]   2> 6356 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[10078 (-1417531287862771712)]} 0 1
[junit4:junit4]   2> 6358 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6359 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[95 (1417531287863820288)]} 0 3
[junit4:junit4]   2> 6359 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6359 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[10078 (-1417531287864868864)]} 0 2
[junit4:junit4]   2> 6361 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[96 (1417531287869063168)]} 0 0
[junit4:junit4]   2> 6362 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10082 (1417531287869063169)]} 0 1
[junit4:junit4]   2> 6366 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6366 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[96 (1417531287871160320)]} 0 3
[junit4:junit4]   2> 6368 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6369 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10082 (1417531287872208896)]} 0 6
[junit4:junit4]   2> 6369 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[97 (1417531287877451776)]} 0 1
[junit4:junit4]   2> 6371 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10083 (1417531287879548928)]} 0 0
[junit4:junit4]   2> 6373 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6373 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[97 (1417531287879548928)]} 0 2
[junit4:junit4]   2> 6375 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[88 (-1417531287883743232)]} 0 0
[junit4:junit4]   2> 6376 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6377 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10083 (1417531287881646080)]} 0 4
[junit4:junit4]   2> 6379 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10084 (1417531287887937536)]} 0 0
[junit4:junit4]   2> 6379 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6380 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[88 (-1417531287886888960)]} 0 2
[junit4:junit4]   2> 6382 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[98 (1417531287890034688)]} 0 1
[junit4:junit4]   2> 6383 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6384 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10084 (1417531287890034688)]} 0 3
[junit4:junit4]   2> 6413 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10085 (1417531287895277568)]} 0 27
[junit4:junit4]   2> 6415 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6415 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[98 (1417531287893180416)]} 0 31
[junit4:junit4]   2> 6417 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[89 (-1417531287927783424)]} 0 0
[junit4:junit4]   2> 6417 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6418 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10085 (1417531287925686272)]} 0 3
[junit4:junit4]   2> 6420 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[10080 (-1417531287930929152)]} 0 0
[junit4:junit4]   2> 6421 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6421 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[89 (-1417531287929880576)]} 0 2
[junit4:junit4]   2> 6423 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6424 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[99 (1417531287934074880)]} 0 1
[junit4:junit4]   2> 6424 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[10080 (-1417531287933026304)]} 0 2
[junit4:junit4]   2> 6426 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[10086 (1417531287937220608)]} 0 0
[junit4:junit4]   2> 6431 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6431 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6431 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[99 (1417531287937220608)]} 0 5
[junit4:junit4]   2> 6431 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10086 (1417531287939317760)]} 0 3
[junit4:junit4]   2> 6435 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10087 (1417531287944560641)]} 0 2
[junit4:junit4]   2> 6436 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[100 (1417531287944560640)]} 0 3
[junit4:junit4]   2> 6439 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6440 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[10087 (1417531287948754944)]} 0 3
[junit4:junit4]   2> 6440 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6440 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[100 (1417531287949803520)]} 0 2
[junit4:junit4]   2> 6442 T1332 C84 P33594 /update {wt=javabin&version=2} {delete=[90 (-1417531287953997825)]} 0 0
[junit4:junit4]   2> 6442 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10088 (1417531287953997824)]} 0 0
[junit4:junit4]   2> 6446 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6447 T1345 C83 P48370 /update {wt=javabin&version=2} {delete=[90 (-1417531287957143552)]} 0 3
[junit4:junit4]   2> 6447 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6447 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[10088 (1417531287957143553)]} 0 3
[junit4:junit4]   2> 6449 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[101 (1417531287961337856)]} 0 0
[junit4:junit4]   2> 6450 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10089 (1417531287961337857)]} 0 1
[junit4:junit4]   2> 6453 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6454 T1347 C83 P48370 /update {wt=javabin&version=2} {add=[101 (1417531287963435008)]} 0 3
[junit4:junit4]   2> 6454 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6455 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[10089 (1417531287964483584)]} 0 3
[junit4:junit4]   2> 6456 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[102 (1417531287968677888)]} 0 1
[junit4:junit4]   2> 6456 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10082 (-1417531287968677889)]} 0 0
[junit4:junit4]   2> 6460 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6460 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6461 T1348 C83 P48370 /update {wt=javabin&version=2} {delete=[10082 (-1417531287970775041)]} 0 3
[junit4:junit4]   2> 6461 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[102 (1417531287970775040)]} 0 3
[junit4:junit4]   2> 6463 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[91 (-1417531287976017921)]} 0 1
[junit4:junit4]   2> 6463 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10090 (1417531287976017920)]} 0 1
[junit4:junit4]   2> 6466 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6467 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[91 (-1417531287978115072)]} 0 2
[junit4:junit4]   2> 6469 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6469 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[10090 (1417531287978115073)]} 0 4
[junit4:junit4]   2> 6469 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[103 (1417531287982309376)]} 0 0
[junit4:junit4]   2> 6472 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10091 (1417531287984406528)]} 0 1
[junit4:junit4]   2> 6474 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6474 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[103 (1417531287984406528)]} 0 3
[junit4:junit4]   2> 6476 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[92 (-1417531287989649408)]} 0 0
[junit4:junit4]   2> 6479 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 6480 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6480 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10091 (1417531287987552256)]} 0 6
[junit4:junit4]   2> 6480 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[92 (-1417531287991746560)]} 0 2
[junit4:junit4]   2> 6482 T1331 C84 P33594 /update {wt=javabin&version=2} {delete=[10085 (-1417531287995940864)]} 0 0
[junit4:junit4]   2> 6482 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[104 (1417531287995940865)]} 0 0
[junit4:junit4]   2> 6486 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6486 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[10085 (-1417531287998038016)]} 0 2
[junit4:junit4]   2> 6487 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6487 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[104 (1417531287998038017)]} 0 3
[junit4:junit4]   2> 6489 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[10092 (1417531288002232320)]} 0 1
[junit4:junit4]   2> 6490 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[93 (-1417531288004329472)]} 0 0
[junit4:junit4]   2> 6493 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6494 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10092 (1417531288005378048)]} 0 3
[junit4:junit4]   2> 6494 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6495 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[93 (-1417531288006426624)]} 0 3
[junit4:junit4]   2> 6496 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10093 (1417531288010620928)]} 0 0
[junit4:junit4]   2> 6497 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[105 (1417531288011669504)]} 0 1
[junit4:junit4]   2> 6501 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6501 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[10093 (1417531288012718080)]} 0 3
[junit4:junit4]   2> 6501 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6502 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[105 (1417531288013766656)]} 0 3
[junit4:junit4]   2> 6503 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[10086 (-1417531288017960960)]} 0 0
[junit4:junit4]   2> 6504 T1330 C84 P33594 /update {wt=javabin&version=2} {delete=[95 (-1417531288019009536)]} 0 1
[junit4:junit4]   2> 6507 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6507 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[10086 (-1417531288020058112)]} 0 2
[junit4:junit4]   2> 6507 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6508 T1346 C83 P48370 /update {wt=javabin&version=2} {delete=[95 (-1417531288020058113)]} 0 3
[junit4:junit4]   2> 6511 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[106 (1417531288025300992)]} 0 1
[junit4:junit4]   2> 6513 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10094 (1417531288024252416)]} 0 4
[junit4:junit4]   2> 6516 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6517 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[106 (1417531288028446720)]} 0 4
[junit4:junit4]   2> 6517 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6518 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10094 (1417531288030543872)]} 0 3
[junit4:junit4]   2> 6519 T1329 C84 P33594 /update {wt=javabin&version=2} {delete=[99 (-1417531288034738176)]} 0 0
[junit4:junit4]   2> 6520 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10095 (1417531288034738177)]} 0 1
[junit4:junit4]   2> 6522 T1360 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6523 T1347 C83 P48370 /update {wt=javabin&version=2} {delete=[99 (-1417531288036835328)]} 0 2
[junit4:junit4]   2> 6524 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6525 T1346 C83 P48370 /update {wt=javabin&version=2} {add=[10095 (1417531288037883904)]} 0 4
[junit4:junit4]   2> 6525 T1332 C84 P33594 /update {wt=javabin&version=2} {add=[107 (1417531288041029632)]} 0 0
[junit4:junit4]   2> 6527 T1331 C84 P33594 /update {wt=javabin&version=2} {add=[10096 (1417531288042078208)]} 0 1
[junit4:junit4]   2> 6530 T1359 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6530 T1348 C83 P48370 /update {wt=javabin&version=2} {add=[107 (1417531288043126784)]} 0 3
[junit4:junit4]   2> 6531 T1357 C82 P37516 REQ /update {distrib.from=http://127.0.0.1:48370/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6531 T1345 C83 P48370 /update {wt=javabin&version=2} {add=[10096 (1417531288045223936)]} 0 2
[junit4:junit4]   2> 6534 T1330 C84 P33594 /update {wt=javabin&version=2} {add=[10097 (1417531288049418240)]} 0 1
[junit4:junit4]   2> 6535 T1329 C84 P33594 /update {wt=javabin&version=2} {add=[108 (1417531288048369664)]} 0 3
[junit4:junit4]   2> 6540 T1358 C82 P37516 REQ /update {distrib.from=http://127.0.

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

/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2/index.20121102093317719
[junit4:junit4]   2> 23905 T1314 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 24006 T1314 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351863175586/jetty2/index.20121102093317719
[junit4:junit4]   2> 24007 T1496 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88595705075335174-127.0.0.1:37516_solr-n_0000000004) am no longer a leader.
[junit4:junit4]   2> 24007 T1319 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ac15309a40006
[junit4:junit4]   2> 24008 T1339 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 24008 T1456 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 24008 T1456 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 24008 T1316 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x13ac15309a40006, likely client has closed socket
[junit4:junit4]   2> 24008 T1456 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 24008 T1314 oaz.ZooKeeper.close Session: 0x13ac15309a40006 closed
[junit4:junit4]   2> 24009 T1316 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51701 which had sessionid 0x13ac15309a40006
[junit4:junit4]   2> 24008 T1456 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 24009 T1339 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4:junit4]   2> 24030 T1314 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 24082 T1319 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ac15309a40003
[junit4:junit4]   2> 24083 T1339 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 24083 T1316 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51681 which had sessionid 0x13ac15309a40003
[junit4:junit4]   2> 24083 T1314 oaz.ZooKeeper.close Session: 0x13ac15309a40003 closed
[junit4:junit4]   2> 24083 T1318 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 24083 T1319 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> 24083 T1314 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 24085 T1316 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> 24085 T1314 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 24086 T1314 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 33594
[junit4:junit4]   2> 24096 T1314 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> 24098 T1314 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 24102 T1313 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4:junit4]   2> 24418 T1317 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {range_facet_l=PostingsFormat(name=Lucene41WithOrds), text=PostingsFormat(name=MockSep), _version_=PostingsFormat(name=Lucene41WithOrds), rnd_b=PostingsFormat(name=NestedPulsing), intDefault=PostingsFormat(name=Lucene41WithOrds), id=PostingsFormat(name=NestedPulsing), timestamp=PostingsFormat(name=Lucene41WithOrds), a_t=PostingsFormat(name=Lucene41WithOrds), range_facet_sl=PostingsFormat(name=MockSep), range_facet_si=PostingsFormat(name=Memory doPackFST= false), other_tl1=PostingsFormat(name=Lucene41WithOrds), multiDefault=PostingsFormat(name=Memory doPackFST= false), a_si=PostingsFormat(name=Memory doPackFST= false)}, sim=DefaultSimilarity, locale=sk_SK, timezone=America/Indiana/Winamac
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic i386/Sun Microsystems Inc. 1.6.0_37 (32-bit)/cpus=8,threads=1,free=375507696,total=530186240
[junit4:junit4]   2> NOTE: All tests run in this JVM: [IndexReaderFactoryTest, DocumentAnalysisRequestHandlerTest, TestSolrJ, BasicZkTest, TestUtils, TestQueryUtils, OverseerTest, ResponseLogComponentTest, RAMDirectoryFactoryTest, PeerSyncTest, SignatureUpdateProcessorFactoryTest, XmlUpdateRequestHandlerTest, HighlighterTest, TestSort, TestDistributedGrouping, TestPluginEnable, SolrInfoMBeanTest, TestWriterPerf, TermVectorComponentTest, LukeRequestHandlerTest, TestLRUCache, QueryEqualityTest, DOMUtilTest, RequiredFieldsTest, TestReplicationHandler, UUIDFieldTest, TestOmitPositions, TestHashPartitioner, DistributedQueryElevationComponentTest, TestAnalyzedSuggestions, BadIndexSchemaTest, PrimitiveFieldTypeTest, MultiTermTest, FileUtilsTest, TestMultiCoreConfBootstrap, TestPropInjectDefaults, ConvertedLegacyTest, DisMaxRequestHandlerTest, ZkCLITest, ZkNodePropsTest, ClusterStateTest, LegacyHTMLStripCharFilterTest, TestRecovery, OutputWriterTest, DebugComponentTest, TestReversedWildcardFilterFactory, TestCodecSupport, TestDistributedSearch, TestSolrDeletionPolicy2, TestFastWriter, DirectSolrSpellCheckerTest, StatelessScriptUpdateProcessorFactoryTest, PolyFieldTest, TestDocumentBuilder, TestRemoteStreaming, TestJmxIntegration, RecoveryZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.seed=2B71EE100690CF68 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sk_SK -Dtests.timezone=America/Indiana/Winamac -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   0.00s J1 | RecoveryZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1364, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([2B71EE100690CF68]:0)
[junit4:junit4]    > 	at java.lang.Thread.getStackTrace(Thread.java:1479)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:514)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:958)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:872)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:864)
[junit4:junit4]    > 	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:232)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:192)
[junit4:junit4]    > 	at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:373)
[junit4:junit4]    > 	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:273)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:152)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:407)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:222)
[junit4:junit4]    > Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.RAMDirectory@13b7091 lockFactory=org.apache.lucene.store.SimpleFSLockFactory@169660b)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4] Completed on J1 in 24.67s, 1 test, 1 error <<< FAILURES!

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

Total time: 27 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.6.0_37 -server -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message