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-Windows (32bit/jdk1.7.0_09) - Build # 1488 - Still Failing!
Date Thu, 08 Nov 2012 14:31:32 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows/1488/
Java: 32bit/jdk1.7.0_09 -server -XX:+UseSerialGC

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=1356, 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=1356, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	at __randomizedtesting.SeedInfo.seed([7C61925BB1291949]:0)
	at java.lang.Thread.getStackTrace(Thread.java:1567)
	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@7cd6df lockFactory=org.apache.lucene.store.SimpleFSLockFactory@d6fe48)
	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:722)




Build Log:
[...truncated 8641 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 5 T1306 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-RecoveryZkTest-1352384289929
[junit4:junit4]   2> 7 T1306 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 11 T1307 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 12 T1307 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 13 T1307 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 112 T1306 oasc.ZkTestServer.run start zk server on port:63999
[junit4:junit4]   2> 112 T1306 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:63999 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7fa860
[junit4:junit4]   2> 116 T1306 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 116 T1312 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:63999
[junit4:junit4]   2> 119 T1312 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:63999, initiating session
[junit4:junit4]   2> 120 T1308 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:64002
[junit4:junit4]   2> 120 T1308 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:64002
[junit4:junit4]   2> 121 T1310 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 141 T1310 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ae0629c900000 with negotiated timeout 10000 for client /127.0.0.1:64002
[junit4:junit4]   2> 141 T1312 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:63999, sessionid = 0x13ae0629c900000, negotiated timeout = 10000
[junit4:junit4]   2> 141 T1313 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7fa860 name:ZooKeeperConnection Watcher:127.0.0.1:63999 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 141 T1306 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 142 T1306 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 168 T1311 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ae0629c900000
[junit4:junit4]   2> 200 T1306 oaz.ZooKeeper.close Session: 0x13ae0629c900000 closed
[junit4:junit4]   2> 200 T1313 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 200 T1308 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x13ae0629c900000, likely client has closed socket
[junit4:junit4]   2> 200 T1306 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:63999/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f576e6
[junit4:junit4]   2> 203 T1308 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:64002 which had sessionid 0x13ae0629c900000
[junit4:junit4]   2> 204 T1306 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 204 T1314 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:63999
[junit4:junit4]   2> 206 T1314 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:63999, initiating session
[junit4:junit4]   2> 206 T1308 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:64005
[junit4:junit4]   2> 207 T1308 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:64005
[junit4:junit4]   2> 220 T1310 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ae0629c900001 with negotiated timeout 10000 for client /127.0.0.1:64005
[junit4:junit4]   2> 220 T1314 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:63999, sessionid = 0x13ae0629c900001, negotiated timeout = 10000
[junit4:junit4]   2> 220 T1315 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f576e6 name:ZooKeeperConnection Watcher:127.0.0.1:63999/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 220 T1306 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 221 T1306 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 255 T1306 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 260 T1306 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 283 T1306 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 290 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 291 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 306 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 307 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 315 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 316 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 322 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 323 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 327 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 328 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 332 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 334 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 339 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 340 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 345 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 347 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 351 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 352 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 357 T1306 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 358 T1306 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 363 T1311 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13ae0629c900001
[junit4:junit4]   2> 364 T1315 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 364 T1308 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x13ae0629c900001, likely client has closed socket
[junit4:junit4]   2> 364 T1306 oaz.ZooKeeper.close Session: 0x13ae0629c900001 closed
[junit4:junit4]   2> 365 T1308 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:64005 which had sessionid 0x13ae0629c900001
[junit4:junit4]   2> 707 T1306 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 734 T1306 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:64006
[junit4:junit4]   2> 735 T1306 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 735 T1306 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287
[junit4:junit4]   2> 735 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287\'
[junit4:junit4]   2> 767 T1306 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 768 T1306 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 768 T1306 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287
[junit4:junit4]   2> 768 T1306 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287\solr.xml
[junit4:junit4]   2> 770 T1306 oasc.CoreContainer.<init> New CoreContainer 599559
[junit4:junit4]   2> 770 T1306 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287\'
[junit4:junit4]   2> 770 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287\'
[junit4:junit4]   2> 796 T1306 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 810 T1306 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:63999/solr
[junit4:junit4]   2> 810 T1306 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:63999/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@38fe78
[junit4:junit4]   2> 811 T1325 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:63999
[junit4:junit4]   2> 811 T1306 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 813 T1325 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:63999, initiating session
[junit4:junit4]   2> 813 T1308 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:64011
[junit4:junit4]   2> 813 T1308 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:64011
[junit4:junit4]   2> 828 T1310 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ae0629c900002 with negotiated timeout 5000 for client /127.0.0.1:64011
[junit4:junit4]   2> 829 T1325 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:63999, sessionid = 0x13ae0629c900002, negotiated timeout = 5000
[junit4:junit4]   2> 830 T1326 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@38fe78 name:ZooKeeperConnection Watcher:127.0.0.1:63999/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 830 T1306 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 862 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 882 T1306 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 904 T1306 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64006_solr
[junit4:junit4]   2> 905 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:64006_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:64006_solr
[junit4:junit4]   2> 924 T1306 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64006_solr
[junit4:junit4]   2> 937 T1306 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 938 T1306 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 939 T1306 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 939 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 940 T1306 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 941 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 942 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 943 T1306 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 944 T1306 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 944 T1306 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 965 T1306 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 1009 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 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> 1028 T1306 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 1048 T1306 oasc.Overseer.start Overseer (id=88629856825245698-127.0.0.1:64006_solr-n_0000000000) starting
[junit4:junit4]   2> 1049 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1061 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1067 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1069 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1083 T1328 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 1085 T1306 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 1096 T1306 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1100 T1327 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 1107 T1306 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287\collection1
[junit4:junit4]   2> 1108 T1306 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 1109 T1306 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1109 T1306 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 1112 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287\collection1\'
[junit4:junit4]   2> 1114 T1306 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1115 T1306 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287/collection1/lib/README' to classloader
[junit4:junit4]   2> 1168 T1306 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1253 T1306 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1255 T1306 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1262 T1306 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1939 T1306 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1950 T1306 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1954 T1306 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1970 T1306 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1974 T1306 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1979 T1306 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1981 T1306 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1983 T1306 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1352384290287\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1352384289927/control/data\
[junit4:junit4]   2> 1983 T1306 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@122914a
[junit4:junit4]   2> 1984 T1306 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 1985 T1306 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bfa00a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a424b6) assuming 'simple'
[junit4:junit4]   2> 1986 T1306 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\control\data forceNew:false
[junit4:junit4]   2> 1987 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\control\data
[junit4:junit4]   2> 1987 T1306 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1352384289927/control/data\index/
[junit4:junit4]   2> 1990 T1306 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1991 T1306 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\control\data\index forceNew:false
[junit4:junit4]   2> 1996 T1306 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@16ffaf9 lockFactory=org.apache.lucene.store.NativeFSLockFactory@24c780),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1997 T1306 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1998 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\control\data\index
[junit4:junit4]   2> 1998 T1306 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2000 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2001 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2001 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2002 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2003 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2003 T1306 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2004 T1306 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2005 T1306 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2006 T1306 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2007 T1306 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2008 T1306 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2009 T1306 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2010 T1306 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2011 T1306 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2011 T1306 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2012 T1306 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2013 T1306 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2014 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2016 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2017 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2018 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2019 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2019 T1306 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2020 T1306 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2021 T1306 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2032 T1306 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2039 T1306 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2041 T1306 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> 2043 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\control\data
[junit4:junit4]   2> 2044 T1306 oass.SolrIndexSearcher.<init> Opening Searcher@178815c main
[junit4:junit4]   2> 2046 T1306 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2046 T1306 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2047 T1306 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2080 T1306 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2153 T1306 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2155 T1329 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@178815c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2156 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 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> 2171 T1306 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2172 T1306 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:64006/solr shardId:control_shard
[junit4:junit4]   2> 2173 T1306 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 2197 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:delete cxid:0x65 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> 2198 T1306 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2199 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2693 T1327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2693 T1327 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:64006_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:64006/solr"}
[junit4:junit4]   2> 2694 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 2722 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 3248 T1306 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3248 T1306 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3249 T1306 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:64006/solr/collection1/
[junit4:junit4]   2> 3251 T1306 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3251 T1306 oasc.SyncStrategy.syncToMe http://127.0.0.1:64006/solr/collection1/ has no replicas
[junit4:junit4]   2> 3252 T1306 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:64006/solr/collection1/
[junit4:junit4]   2> 3253 T1306 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 3302 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900002 type:create cxid:0x8f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3783 T1327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3854 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 3928 T1306 oasc.ZkController.register We are http://127.0.0.1:64006/solr/collection1/ and leader is http://127.0.0.1:64006/solr/collection1/
[junit4:junit4]   2> 3929 T1306 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:64006/solr
[junit4:junit4]   2> 3930 T1306 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3930 T1306 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3951 T1306 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3953 T1306 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 3953 T1306 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3954 T1306 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3976 T1306 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3982 T1306 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:63999/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1444a19
[junit4:junit4]   2> 3983 T1330 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:63999
[junit4:junit4]   2> 3983 T1306 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3984 T1330 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:63999, initiating session
[junit4:junit4]   2> 3984 T1308 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:64014
[junit4:junit4]   2> 3985 T1308 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:64014
[junit4:junit4]   2> 4001 T1310 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ae0629c900003 with negotiated timeout 10000 for client /127.0.0.1:64014
[junit4:junit4]   2> 4002 T1330 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:63999, sessionid = 0x13ae0629c900003, negotiated timeout = 10000
[junit4:junit4]   2> 4002 T1331 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1444a19 name:ZooKeeperConnection Watcher:127.0.0.1:63999/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4003 T1306 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4004 T1306 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4008 T1306 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4319 T1306 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 4323 T1306 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:64015
[junit4:junit4]   2> 4324 T1306 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4324 T1306 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930
[junit4:junit4]   2> 4325 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930\'
[junit4:junit4]   2> 4351 T1306 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4352 T1306 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4352 T1306 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930
[junit4:junit4]   2> 4352 T1306 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930\solr.xml
[junit4:junit4]   2> 4352 T1306 oasc.CoreContainer.<init> New CoreContainer 30857531
[junit4:junit4]   2> 4354 T1306 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930\'
[junit4:junit4]   2> 4354 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930\'
[junit4:junit4]   2> 4377 T1306 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4389 T1306 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:63999/solr
[junit4:junit4]   2> 4390 T1306 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:63999/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@8989d2
[junit4:junit4]   2> 4391 T1306 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4391 T1341 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:63999
[junit4:junit4]   2> 4393 T1341 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:63999, initiating session
[junit4:junit4]   2> 4393 T1308 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:64020
[junit4:junit4]   2> 4393 T1308 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:64020
[junit4:junit4]   2> 4406 T1310 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ae0629c900004 with negotiated timeout 5000 for client /127.0.0.1:64020
[junit4:junit4]   2> 4407 T1341 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:63999, sessionid = 0x13ae0629c900004, negotiated timeout = 5000
[junit4:junit4]   2> 4407 T1342 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8989d2 name:ZooKeeperConnection Watcher:127.0.0.1:63999/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4407 T1306 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4409 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4411 T1327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4411 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4413 T1327 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:64006_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:64006/solr"}
[junit4:junit4]   2> 4414 T1306 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64015_solr
[junit4:junit4]   2> 4415 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:64015_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:64015_solr
[junit4:junit4]   2> 4417 T1306 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64015_solr
[junit4:junit4]   2> 4422 T1331 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4422 T1306 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4423 T1306 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4423 T1306 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4424 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4424 T1306 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4424 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4424 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4425 T1306 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4425 T1306 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4425 T1306 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4433 T1331 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4447 T1326 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4447 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4447 T1306 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4449 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4458 T1306 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930\collection1
[junit4:junit4]   2> 4458 T1306 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4459 T1306 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4460 T1306 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4464 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930\collection1\'
[junit4:junit4]   2> 4466 T1306 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4468 T1306 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930/collection1/lib/README' to classloader
[junit4:junit4]   2> 4528 T1306 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4600 T1306 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4617 T1306 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4626 T1306 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5276 T1306 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5287 T1306 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5290 T1306 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5308 T1306 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5322 T1306 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5328 T1306 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5330 T1306 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5331 T1306 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1352384293930\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1352384289927/jetty1\
[junit4:junit4]   2> 5332 T1306 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@122914a
[junit4:junit4]   2> 5333 T1306 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5334 T1306 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@d9374c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bd7073) assuming 'simple'
[junit4:junit4]   2> 5334 T1306 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty1 forceNew:false
[junit4:junit4]   2> 5335 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty1
[junit4:junit4]   2> 5335 T1306 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1352384289927/jetty1\index/
[junit4:junit4]   2> 5337 T1306 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5337 T1306 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty1\index forceNew:false
[junit4:junit4]   2> 5341 T1306 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f259fe lockFactory=org.apache.lucene.store.NativeFSLockFactory@334d37),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5341 T1306 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5342 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty1\index
[junit4:junit4]   2> 5343 T1306 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5345 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5345 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5347 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5347 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5348 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5348 T1306 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5349 T1306 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5350 T1306 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5351 T1306 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5352 T1306 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5353 T1306 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5353 T1306 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5355 T1306 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5355 T1306 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5356 T1306 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5356 T1306 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5357 T1306 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5358 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5359 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5360 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5361 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5362 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5363 T1306 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5364 T1306 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5365 T1306 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5376 T1306 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5383 T1306 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5385 T1306 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> 5387 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty1
[junit4:junit4]   2> 5388 T1306 oass.SolrIndexSearcher.<init> Opening Searcher@ddf4e6 main
[junit4:junit4]   2> 5390 T1306 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5390 T1306 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5391 T1306 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5412 T1306 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5475 T1343 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ddf4e6 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5478 T1306 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5537 T1327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5537 T1327 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:64015_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:64015/solr"}
[junit4:junit4]   2> 5537 T1327 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 5545 T1342 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5545 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5545 T1331 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6004 T1306 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 6004 T1306 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:64015/solr shardId:shard1
[junit4:junit4]   2> 6005 T1306 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 6021 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900004 type:delete cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 6026 T1306 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6027 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900004 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6038 T1306 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6039 T1306 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6039 T1306 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:64015/solr/collection1/
[junit4:junit4]   2> 6040 T1306 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6040 T1306 oasc.SyncStrategy.syncToMe http://127.0.0.1:64015/solr/collection1/ has no replicas
[junit4:junit4]   2> 6042 T1306 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:64015/solr/collection1/
[junit4:junit4]   2> 6042 T1306 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 6055 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900004 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6075 T1327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6091 T1331 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6091 T1342 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6091 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6118 T1306 oasc.ZkController.register We are http://127.0.0.1:64015/solr/collection1/ and leader is http://127.0.0.1:64015/solr/collection1/
[junit4:junit4]   2> 6118 T1306 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:64015/solr
[junit4:junit4]   2> 6118 T1306 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 6118 T1306 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6121 T1306 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6122 T1306 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 6122 T1306 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6123 T1306 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6458 T1306 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 6462 T1306 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:64021
[junit4:junit4]   2> 6463 T1306 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6463 T1306 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054
[junit4:junit4]   2> 6464 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054\'
[junit4:junit4]   2> 6512 T1306 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6512 T1306 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6513 T1306 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054
[junit4:junit4]   2> 6513 T1306 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054\solr.xml
[junit4:junit4]   2> 6514 T1306 oasc.CoreContainer.<init> New CoreContainer 4958527
[junit4:junit4]   2> 6514 T1306 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054\'
[junit4:junit4]   2> 6515 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054\'
[junit4:junit4]   2> 6540 T1306 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6557 T1306 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:63999/solr
[junit4:junit4]   2> 6557 T1306 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:63999/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@193260d
[junit4:junit4]   2> 6559 T1353 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:63999
[junit4:junit4]   2> 6559 T1306 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6561 T1353 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:63999, initiating session
[junit4:junit4]   2> 6561 T1308 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:64026
[junit4:junit4]   2> 6562 T1308 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:64026
[junit4:junit4]   2> 6572 T1310 oazs.NIOServerCnxn.finishSessionInit Established session 0x13ae0629c900005 with negotiated timeout 5000 for client /127.0.0.1:64026
[junit4:junit4]   2> 6572 T1353 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:63999, sessionid = 0x13ae0629c900005, negotiated timeout = 5000
[junit4:junit4]   2> 6573 T1354 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@193260d name:ZooKeeperConnection Watcher:127.0.0.1:63999/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6573 T1306 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6574 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6577 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6579 T1306 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64021_solr
[junit4:junit4]   2> 6579 T1311 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13ae0629c900005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:64021_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:64021_solr
[junit4:junit4]   2> 6581 T1306 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64021_solr
[junit4:junit4]   2> 6584 T1331 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 6586 T1326 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 6586 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6586 T1331 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6586 T1342 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 6587 T1342 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6586 T1306 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6587 T1306 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6588 T1306 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6588 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6588 T1306 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6590 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6590 T1306 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6591 T1306 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6591 T1306 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6592 T1306 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6611 T1306 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6619 T1306 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054\collection1
[junit4:junit4]   2> 6620 T1306 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6621 T1306 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6621 T1306 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6625 T1327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6626 T1306 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054\collection1\'
[junit4:junit4]   2> 6627 T1327 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:64015_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:64015/solr"}
[junit4:junit4]   2> 6628 T1306 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6629 T1306 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054/collection1/lib/README' to classloader
[junit4:junit4]   2> 6645 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6646 T1342 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6646 T1354 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6646 T1331 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6683 T1306 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 6742 T1306 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6744 T1306 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6749 T1306 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7387 T1306 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7395 T1306 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7398 T1306 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7422 T1306 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7428 T1306 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7433 T1306 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7434 T1306 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7436 T1306 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1352384296054\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1352384289927/jetty2\
[junit4:junit4]   2> 7436 T1306 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@122914a
[junit4:junit4]   2> 7437 T1306 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 7438 T1306 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b5bb43 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1957ab) assuming 'simple'
[junit4:junit4]   2> 7438 T1306 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty2 forceNew:false
[junit4:junit4]   2> 7438 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty2
[junit4:junit4]   2> 7439 T1306 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1352384289927/jetty2\index/
[junit4:junit4]   2> 7440 T1306 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7444 T1306 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty2\index forceNew:false
[junit4:junit4]   2> 7449 T1306 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\index3818938198tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a2d2f4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7450 T1306 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 7451 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty2\index
[junit4:junit4]   2> 7451 T1306 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7452 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7453 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7453 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7454 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7454 T1306 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7455 T1306 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7456 T1306 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7456 T1306 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7457 T1306 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7458 T1306 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7459 T1306 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7460 T1306 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7461 T1306 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7461 T1306 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7461 T1306 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7462 T1306 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7463 T1306 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7464 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7465 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7466 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7467 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7468 T1306 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7468 T1306 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7469 T1306 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7470 T1306 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7482 T1306 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7487 T1306 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7488 T1306 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> 7491 T1306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty2
[junit4:junit4]   2> 7492 T1306 oass.SolrIndexSearcher.<init> Opening Searcher@25e2e1 main
[junit4:junit4]   2> 7495 T1306 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7496 T1306 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7497 T1306 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7526 T1306 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7750 T1355 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25e2e1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7757 T1306 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8233 T1327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8233 T1327 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:64021_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:64021/solr"}
[junit4:junit4]   2> 8240 T1331 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8240 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8240 T1342 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8240 T1354 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8292 T1306 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 8292 T1306 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:64021/solr shardId:shard1
[junit4:junit4]   2> 8297 T1306 oasc.ZkController.register We are http://127.0.0.1:64021/solr/collection1/ and leader is http://127.0.0.1:64015/solr/collection1/
[junit4:junit4]   2> 8297 T1306 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:64021/solr
[junit4:junit4]   2> 8297 T1306 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 8297 T1306 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C111 name=collection1 org.apache.solr.core.SolrCore@12452c3 url=http://127.0.0.1:64021/solr/collection1 node=127.0.0.1:64021_solr
[junit4:junit4]   2> 8299 T1356 C111 P64021 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 8299 T1306 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8299 T1356 C111 P64021 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 8300 T1356 C111 P64021 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8300 T1306 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 8301 T1306 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8301 T1306 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8303 T1356 C111 P64021 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:64015/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 8303 T1356 C111 P64021 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:64021/solr START replicas=[http://127.0.0.1:64015/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 8304 T1356 C111 P64021 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 8306 T1356 C111 P64021 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 8306 T1356 C111 P64021 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 8306 T1356 C111 P64021 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8312 T1306 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C112 name=collection1 org.apache.solr.core.SolrCore@23425a url=http://127.0.0.1:64015/solr/collection1 node=127.0.0.1:64015_solr
[junit4:junit4]   2> 8319 T1336 C112 P64015 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> ASYNC  NEW_CORE C113 name=collection1 org.apache.solr.core.SolrCore@e1b341 url=http://127.0.0.1:64006/solr/collection1 node=127.0.0.1:64006_solr
[junit4:junit4]   2> 8333 T1319 C113 P64006 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\control\data
[junit4:junit4]   2> 8337 T1319 C113 P64006 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@16ffaf9 lockFactory=org.apache.lucene.store.NativeFSLockFactory@24c780),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8339 T1319 C113 P64006 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8342 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[1 (1418077717927034880)]} 0 10
[junit4:junit4]   2> 8348 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10001 (1418077717939617792)]} 0 8
[junit4:junit4]   2> 8353 T1340 C112 P64015 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty1
[junit4:junit4]   2> 8360 T1340 C112 P64015 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f259fe lockFactory=org.apache.lucene.store.NativeFSLockFactory@334d37),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8361 T1340 C112 P64015 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8364 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[1 (1418077717946957824)]} 0 12
[junit4:junit4]   2> 8366 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10001 (1418077717960589312)]} 0 1
[junit4:junit4]   2> 8388 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[2 (1418077717978415104)]} 0 6
[junit4:junit4]   2> 8393 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10002 (1418077717988900864)]} 0 1
[junit4:junit4]   2> 8396 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[2 (1418077717992046592)]} 0 1
[junit4:junit4]   2> 8398 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10002 (1418077717995192320)]} 0 1
[junit4:junit4]   2> 8399 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[3 (1418077717996240896)]} 0 0
[junit4:junit4]   2> 8402 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10003 (1418077717998338048)]} 0 1
[junit4:junit4]   2> 8403 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[3 (1418077718000435200)]} 0 0
[junit4:junit4]   2> 8407 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10003 (1418077718003580928)]} 0 1
[junit4:junit4]   2> 8408 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[4 (1418077718004629504)]} 0 1
[junit4:junit4]   2> 8409 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10004 (1418077718006726656)]} 0 0
[junit4:junit4]   2> 8412 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[4 (1418077718008823808)]} 0 1
[junit4:junit4]   2> 8413 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10004 (1418077718010920960)]} 0 0
[junit4:junit4]   2> 8414 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[5 (1418077718011969536)]} 0 0
[junit4:junit4]   2> 8419 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10005 (1418077718014066688)]} 0 3
[junit4:junit4]   2> 8422 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[5 (1418077718019309568)]} 0 1
[junit4:junit4]   2> 8426 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10005 (1418077718021406720)]} 0 3
[junit4:junit4]   2> 8429 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[6 (1418077718025601024)]} 0 2
[junit4:junit4]   2> 8432 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10006 (1418077718030843904)]} 0 0
[junit4:junit4]   2> 8435 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[6 (1418077718031892480)]} 0 2
[junit4:junit4]   2> 8436 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10006 (1418077718035038208)]} 0 0
[junit4:junit4]   2> 8437 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[7 (1418077718036086784)]} 0 0
[junit4:junit4]   2> 8439 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10007 (1418077718038183936)]} 0 0
[junit4:junit4]   2> 8441 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[7 (1418077718040281088)]} 0 0
[junit4:junit4]   2> 8443 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10007 (1418077718042378240)]} 0 1
[junit4:junit4]   2> 8445 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[2 (-1418077718044475392)]} 0 0
[junit4:junit4]   2> 8449 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[10002 (-1418077718046572544)]} 0 3
[junit4:junit4]   2> 8450 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[2 (-1418077718049718272)]} 0 0
[junit4:junit4]   2> 8452 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[8 (1418077718051815424)]} 0 0
[junit4:junit4]   2> 8455 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10002 (-1418077718053912576)]} 0 1
[junit4:junit4]   2> 8462 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[8 (1418077718062301184)]} 0 0
[junit4:junit4]   2> 8465 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10008 (1418077718064398336)]} 0 1
[junit4:junit4]   2> 8466 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[6 (-1418077718066495488)]} 0 0
[junit4:junit4]   2> 8467 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10008 (1418077718067544064)]} 0 0
[junit4:junit4]   2> 8470 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[6 (-1418077718070689792)]} 0 0
[junit4:junit4]   2> 8471 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10006 (-1418077718071738368)]} 0 0
[junit4:junit4]   2> 8473 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10006 (-1418077718073835520)]} 0 0
[junit4:junit4]   2> 8475 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[9 (1418077718074884096)]} 0 1
[junit4:junit4]   2> 8476 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10009 (1418077718075932672)]} 0 1
[junit4:junit4]   2> 8478 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10009 (1418077718079078400)]} 0 0
[junit4:junit4]   2> 8486 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10008 (-1418077718087467008)]} 0 0
[junit4:junit4]   2> 8488 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[9 (1418077718089564160)]} 0 0
[junit4:junit4]   2> 8490 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10008 (-1418077718091661312)]} 0 0
[junit4:junit4]   2> 8492 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10010 (1418077718093758464)]} 0 0
[junit4:junit4]   2> 8494 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[8 (-1418077718095855616)]} 0 1
[junit4:junit4]   2> 8498 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10010 (1418077718100049920)]} 0 3
[junit4:junit4]   2> 8501 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[8 (-1418077718103195648)]} 0 0
[junit4:junit4]   2> 8512 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10011 (1418077718104244224)]} 0 10
[junit4:junit4]   2> 8514 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10 (1418077718116827136)]} 0 1
[junit4:junit4]   2> 8521 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10 (1418077718124167168)]} 0 0
[junit4:junit4]   2> 8523 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10011 (1418077718119972864)]} 0 7
[junit4:junit4]   2> 8525 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[11 (1418077718127312896)]} 0 1
[junit4:junit4]   2> 8526 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10012 (1418077718129410048)]} 0 0
[junit4:junit4]   2> 8529 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10012 (1418077718132555776)]} 0 0
[junit4:junit4]   2> 8531 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[11 (1418077718133604352)]} 0 1
[junit4:junit4]   2> 8532 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[10009 (-1418077718135701504)]} 0 0
[junit4:junit4]   2> 8535 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[12 (1418077718137798656)]} 0 1
[junit4:junit4]   2> 8536 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10009 (-1418077718139895808)]} 0 0
[junit4:junit4]   2> 8539 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10013 (1418077718143041536)]} 0 0
[junit4:junit4]   2> 8541 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[12 (1418077718145138688)]} 0 0
[junit4:junit4]   2> 8543 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10013 (1418077718147235840)]} 0 0
[junit4:junit4]   2> 8545 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10014 (1418077718149332992)]} 0 0
[junit4:junit4]   2> 8546 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[9 (-1418077718150381568)]} 0 0
[junit4:junit4]   2> 8549 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10014 (1418077718152478720)]} 0 1
[junit4:junit4]   2> 8554 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[9 (-1418077718158770176)]} 0 0
[junit4:junit4]   2> 8555 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10015 (1418077718159818752)]} 0 0
[junit4:junit4]   2> 8559 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10015 (1418077718162964480)]} 0 1
[junit4:junit4]   2> 8564 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[13 (1418077718168207360)]} 0 1
[junit4:junit4]   2> 8566 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10016 (1418077718170304512)]} 0 1
[junit4:junit4]   2> 8571 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10016 (1418077718175547392)]} 0 1
[junit4:junit4]   2> 8571 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[13 (1418077718176595968)]} 0 0
[junit4:junit4]   2> 8575 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[10013 (-1418077718180790272)]} 0 0
[junit4:junit4]   2> 8578 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[10013 (-1418077718183936000)]} 0 0
[junit4:junit4]   2> 8580 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[14 (1418077718179741696)]} 0 6
[junit4:junit4]   2> 8583 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10017 (1418077718188130304)]} 0 1
[junit4:junit4]   2> 8588 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10017 (1418077718191276032)]} 0 3
[junit4:junit4]   2> 8590 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[14 (1418077718195470336)]} 0 1
[junit4:junit4]   2> 8591 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10018 (1418077718197567488)]} 0 0
[junit4:junit4]   2> 8593 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[15 (1418077718199664640)]} 0 0
[junit4:junit4]   2> 8595 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10018 (1418077718201761792)]} 0 1
[junit4:junit4]   2> 8597 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[10014 (-1418077718203858944)]} 0 0
[junit4:junit4]   2> 8600 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[10014 (-1418077718207004672)]} 0 0
[junit4:junit4]   2> 8601 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[15 (1418077718204907520)]} 0 3
[junit4:junit4]   2> 8604 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10019 (1418077718211198976)]} 0 0
[junit4:junit4]   2> 8606 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[16 (1418077718212247552)]} 0 1
[junit4:junit4]   2> 8609 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10019 (1418077718215393280)]} 0 1
[junit4:junit4]   2> 8613 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[16 (1418077718218539008)]} 0 2
[junit4:junit4]   2> 8614 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10020 (1418077718221684736)]} 0 0
[junit4:junit4]   2> 8618 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10020 (1418077718224830464)]} 0 1
[junit4:junit4]   2> 8619 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[13 (-1418077718225879040)]} 0 1
[junit4:junit4]   2> 8622 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10021 (1418077718229024768)]} 0 1
[junit4:junit4]   2> 8624 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10021 (1418077718232170496)]} 0 0
[junit4:junit4]   2> 8625 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[13 (-1418077718233219072)]} 0 0
[junit4:junit4]   2> 8627 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[10015 (-1418077718235316224)]} 0 0
[junit4:junit4]   2> 8628 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[17 (1418077718236364800)]} 0 0
[junit4:junit4]   2> 8631 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10015 (-1418077718239510528)]} 0 2
[junit4:junit4]   2> 8632 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[17 (1418077718240559104)]} 0 0
[junit4:junit4]   2> 8634 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10022 (1418077718241607680)]} 0 1
[junit4:junit4]   2> 8638 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[18 (1418077718244753408)]} 0 2
[junit4:junit4]   2> 8640 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10022 (1418077718247899136)]} 0 1
[junit4:junit4]   2> 8648 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[18 (1418077718253142016)]} 0 4
[junit4:junit4]   2> 8651 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10018 (-1418077718260482048)]} 0 1
[junit4:junit4]   2> 8652 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[14 (-1418077718261530624)]} 0 0
[junit4:junit4]   2> 8654 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10018 (-1418077718263627776)]} 0 1
[junit4:junit4]   2> 8658 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[14 (-1418077718267822080)]} 0 0
[junit4:junit4]   2> 8665 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10023 (1418077718274113536)]} 0 2
[junit4:junit4]   2> 8668 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[19 (1418077718277259264)]} 0 1
[junit4:junit4]   2> 8672 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[19 (1418077718281453568)]} 0 1
[junit4:junit4]   2> 8673 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10023 (1418077718283550720)]} 0 0
[junit4:junit4]   2> 8675 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[20 (1418077718284599296)]} 0 1
[junit4:junit4]   2> 8677 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10019 (-1418077718287745024)]} 0 1
[junit4:junit4]   2> 8678 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[20 (1418077718288793600)]} 0 0
[junit4:junit4]   2> 8679 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10019 (-1418077718289842176)]} 0 0
[junit4:junit4]   2> 8682 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[21 (1418077718291939328)]} 0 1
[junit4:junit4]   2> 8683 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10024 (1418077718294036480)]} 0 0
[junit4:junit4]   2> 8685 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[21 (1418077718296133632)]} 0 0
[junit4:junit4]   2> 8686 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10024 (1418077718297182208)]} 0 0
[junit4:junit4]   2> 8689 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[15 (-1418077718300327936)]} 0 0
[junit4:junit4]   2> 8690 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10025 (1418077718301376512)]} 0 1
[junit4:junit4]   2> 8691 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[15 (-1418077718302425088)]} 0 0
[junit4:junit4]   2> 8693 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10025 (1418077718304522240)]} 0 0
[junit4:junit4]   2> 8695 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[22 (1418077718305570816)]} 0 1
[junit4:junit4]   2> 8696 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10020 (-1418077718307667968)]} 0 0
[junit4:junit4]   2> 8697 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[22 (1418077718308716544)]} 0 0
[junit4:junit4]   2> 8699 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10020 (-1418077718310813696)]} 0 0
[junit4:junit4]   2> 8700 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[18 (-1418077718311862272)]} 0 0
[junit4:junit4]   2> 8702 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10026 (1418077718313959424)]} 0 0
[junit4:junit4]   2> 8703 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[18 (-1418077718315008000)]} 0 0
[junit4:junit4]   2> 8707 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10026 (1418077718318153728)]} 0 1
[junit4:junit4]   2> 8707 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[23 (1418077718319202304)]} 0 0
[junit4:junit4]   2> 8709 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10023 (-1418077718321299456)]} 0 0
[junit4:junit4]   2> 8710 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[23 (1418077718322348032)]} 0 0
[junit4:junit4]   2> 8712 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10023 (-1418077718324445184)]} 0 0
[junit4:junit4]   2> 8714 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[19 (-1418077718326542336)]} 0 1
[junit4:junit4]   2> 8716 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[19 (-1418077718328639488)]} 0 0
[junit4:junit4]   2> 8719 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10027 (1418077718327590912)]} 0 4
[junit4:junit4]   2> 8720 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[24 (1418077718331785216)]} 0 1
[junit4:junit4]   2> 8724 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[24 (1418077718335979521)]} 0 1
[junit4:junit4]   2> 8728 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[25 (1418077718341222400)]} 0 1
[junit4:junit4]   2> 8728 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10027 (1418077718335979520)]} 0 5
[junit4:junit4]   2> 8730 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[25 (1418077718343319552)]} 0 0
[junit4:junit4]   2> 8731 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10028 (1418077718344368128)]} 0 0
[junit4:junit4]   2> 8735 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[20 (-1418077718348562432)]} 0 1
[junit4:junit4]   2> 8736 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10028 (1418077718349611008)]} 0 0
[junit4:junit4]   2> 8738 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[20 (-1418077718351708160)]} 0 0
[junit4:junit4]   2> 8740 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10029 (1418077718352756736)]} 0 1
[junit4:junit4]   2> 8741 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[26 (1418077718354853888)]} 0 0
[junit4:junit4]   2> 8744 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10029 (1418077718357999616)]} 0 0
[junit4:junit4]   2> 8746 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[26 (1418077718360096768)]} 0 0
[junit4:junit4]   2> 8748 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10025 (-1418077718362193920)]} 0 0
[junit4:junit4]   2> 8749 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[23 (-1418077718363242496)]} 0 0
[junit4:junit4]   2> 8752 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[10025 (-1418077718366388224)]} 0 0
[junit4:junit4]   2> 8753 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[23 (-1418077718367436800)]} 0 0
[junit4:junit4]   2> 8756 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10030 (1418077718370582528)]} 0 0
[junit4:junit4]   2> 8757 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[27 (1418077718371631104)]} 0 0
[junit4:junit4]   2> 8759 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[27 (1418077718373728256)]} 0 0
[junit4:junit4]   2> 8760 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10030 (1418077718373728257)]} 0 1
[junit4:junit4]   2> 8762 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10027 (-1418077718376873984)]} 0 0
[junit4:junit4]   2> 8762 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[28 (1418077718376873985)]} 0 0
[junit4:junit4]   2> 8765 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10027 (-1418077718380019712)]} 0 0
[junit4:junit4]   2> 8765 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[28 (1418077718380019713)]} 0 0
[junit4:junit4]   2> 8769 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10031 (1418077718383165440)]} 0 1
[junit4:junit4]   2> 8771 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10031 (1418077718386311168)]} 0 0
[junit4:junit4]   2> 8772 T1327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8772 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[29 (1418077718383165441)]} 0 4
[junit4:junit4]   2> 8772 T1327 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:64021_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:64021/solr"}
[junit4:junit4]   2> 8774 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[10028 (-1418077718389456896)]} 0 0
[junit4:junit4]   2> 8777 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10028 (-1418077718392602624)]} 0 0
[junit4:junit4]   2> 8777 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[29 (1418077718390505472)]} 0 2
[junit4:junit4]   2> 8780 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[25 (-1418077718395748352)]} 0 0
[junit4:junit4]   2> 8781 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10032 (1418077718395748353)]} 0 1
[junit4:junit4]   2> 8783 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[25 (-1418077718398894080)]} 0 0
[junit4:junit4]   2> 8784 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10032 (1418077718398894081)]} 0 1
[junit4:junit4]   2> 8786 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10033 (1418077718402039808)]} 0 0
[junit4:junit4]   2> 8787 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[30 (1418077718402039809)]} 0 1
[junit4:junit4]   2> 8789 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[30 (1418077718405185536)]} 0 0
[junit4:junit4]   2> 8790 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10033 (1418077718405185537)]} 0 1
[junit4:junit4]   2> 8792 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[27 (-1418077718408331265)]} 0 0
[junit4:junit4]   2> 8792 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10034 (1418077718408331264)]} 0 0
[junit4:junit4]   2> 8795 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[27 (-1418077718411476993)]} 0 0
[junit4:junit4]   2> 8795 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10034 (1418077718411476992)]} 0 0
[junit4:junit4]   2> 8798 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[31 (1418077718414622720)]} 0 0
[junit4:junit4]   2> 8801 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10035 (1418077718416719872)]} 0 1
[junit4:junit4]   2> 8802 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[31 (1418077718418817024)]} 0 0
[junit4:junit4]   2> 8804 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10035 (1418077718420914176)]} 0 0
[junit4:junit4]   2> 8805 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[28 (-1418077718421962752)]} 0 0
[junit4:junit4]   2> 8807 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10029 (-1418077718424059904)]} 0 0
[junit4:junit4]   2> 8808 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[28 (-1418077718425108480)]} 0 0
[junit4:junit4]   2> 8810 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10029 (-1418077718427205632)]} 0 0
[junit4:junit4]   2> 8812 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[32 (1418077718428254208)]} 0 1
[junit4:junit4]   2> 8814 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10036 (1418077718430351360)]} 0 1
[junit4:junit4]   2> 8815 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[32 (1418077718432448512)]} 0 1
[junit4:junit4]   2> 8817 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10036 (1418077718433497088)]} 0 1
[junit4:junit4]   2> 8819 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[33 (1418077718436642816)]} 0 1
[junit4:junit4]   2> 8823 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[33 (1418077718440837120)]} 0 0
[junit4:junit4]   2> 8824 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10037 (1418077718438739968)]} 0 3
[junit4:junit4]   2> 8827 T1326 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8828 T1354 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8828 T1342 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8827 T1331 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8828 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[34 (1418077718443982848)]} 0 2
[junit4:junit4]   2> 8833 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10037 (1418077718445031424)]} 0 6
[junit4:junit4]   2> 8836 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10031 (-1418077718454468608)]} 0 0
[junit4:junit4]   2>  C111_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:64021_solr, base_url=http://127.0.0.1:64021/solr}
[junit4:junit4]   2> 8860 T1352 C111 P64021 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1352384289927\jetty2
[junit4:junit4]   2> 8867 T1352 C111 P64021 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\index3818938198tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a2d2f4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8867 T1352 C111 P64021 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8869 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 8871 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=12 
[junit4:junit4]   2> 8871 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[34 (1418077718451322880)]} 0 39
[junit4:junit4]   2> 8871 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[10031 (-1418077718476488704)]} 0 14
[junit4:junit4]   2> 8875 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10038 (1418077718494314496)]} 0 1
[junit4:junit4]   2> 8875 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[35 (1418077718494314497)]} 0 1
[junit4:junit4]   2> 8882 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 8883 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10038 (1418077718497460224)]} 0 6
[junit4:junit4]   2> 8883 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8884 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[35 (1418077718498508800)]} 0 6
[junit4:junit4]   2> 8885 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10032 (-1418077718505848832)]} 0 0
[junit4:junit4]   2> 8886 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[29 (-1418077718506897408)]} 0 0
[junit4:junit4]   2> 8892 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8894 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[10032 (-1418077718508994560)]} 0 6
[junit4:junit4]   2> 8895 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 8896 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[29 (-1418077718510043136)]} 0 7
[junit4:junit4]   2> 8897 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10039 (1418077718518431744)]} 0 0
[junit4:junit4]   2> 8900 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[36 (1418077718520528896)]} 0 1
[junit4:junit4]   2> 8904 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8905 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10039 (1418077718521577472)]} 0 5
[junit4:junit4]   2> 8907 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 8908 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[36 (1418077718523674624)]} 0 6
[junit4:junit4]   2> 8909 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10040 (1418077718531014656)]} 0 0
[junit4:junit4]   2> 8912 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[37 (1418077718533111808)]} 0 1
[junit4:junit4]   2> 8918 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8920 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10040 (1418077718536257536)]} 0 6
[junit4:junit4]   2> 8920 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8921 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[37 (1418077718539403264)]} 0 5
[junit4:junit4]   2> 8923 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10033 (-1418077718545694720)]} 0 1
[junit4:junit4]   2> 8925 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[31 (-1418077718546743296)]} 0 1
[junit4:junit4]   2> 8929 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8930 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10033 (-1418077718549889024)]} 0 4
[junit4:junit4]   2> 8932 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8933 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[31 (-1418077718551986176)]} 0 5
[junit4:junit4]   2> 8935 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10041 (1418077718557229056)]} 0 1
[junit4:junit4]   2> 8937 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[38 (1418077718560374784)]} 0 1
[junit4:junit4]   2> 8945 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8946 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10041 (1418077718562471936)]} 0 8
[junit4:junit4]   2> 8947 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8949 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[38 (1418077718563520512)]} 0 9
[junit4:junit4]   2> 8950 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[10034 (-1418077718574006272)]} 0 0
[junit4:junit4]   2> 8953 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[32 (-1418077718577152000)]} 0 0
[junit4:junit4]   2> 8958 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8959 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[10034 (-1418077718579249152)]} 0 4
[junit4:junit4]   2> 8959 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8961 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[32 (-1418077718580297728)]} 0 5
[junit4:junit4]   2> 8967 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[39 (1418077718588686336)]} 0 3
[junit4:junit4]   2> 8995 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10042 (1418077718587637760)]} 0 32
[junit4:junit4]   2> 9018 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=17 
[junit4:junit4]   2> 9019 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[39 (1418077718594977792)]} 0 49
[junit4:junit4]   2> 9050 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[40 (1418077718649503744)]} 0 28
[junit4:junit4]   2> 9076 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=18 
[junit4:junit4]   2> 9077 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=22 
[junit4:junit4]   2> 9078 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10042 (1418077718625386496)]} 0 79
[junit4:junit4]   2> 9079 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[40 (1418077718684106752)]} 0 26
[junit4:junit4]   2> 9081 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[10035 (-1418077718711369728)]} 0 0
[junit4:junit4]   2> 9084 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[33 (-1418077718714515456)]} 0 0
[junit4:junit4]   2> 9089 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9089 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9090 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[33 (-1418077718716612609)]} 0 4
[junit4:junit4]   2> 9090 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[10035 (-1418077718716612608)]} 0 4
[junit4:junit4]   2> 9093 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[41 (1418077718722904064)]} 0 1
[junit4:junit4]   2> 9093 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10043 (1418077718723952640)]} 0 0
[junit4:junit4]   2> 9110 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9112 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10043 (1418077718727098368)]} 0 16
[junit4:junit4]   2> 9112 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9112 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[41 (1418077718726049792)]} 0 17
[junit4:junit4]   2> 9115 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10044 (1418077718745972736)]} 0 1
[junit4:junit4]   2> 9116 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[34 (-1418077718747021312)]} 0 1
[junit4:junit4]   2> 9120 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9121 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[34 (-1418077718750167041)]} 0 3
[junit4:junit4]   2> 9124 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[42 (1418077718756458496)]} 0 0
[junit4:junit4]   2> 9170 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9172 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10044 (1418077718750167040)]} 0 54
[junit4:junit4]   2> 9172 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9173 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[42 (1418077718760652800)]} 0 45
[junit4:junit4]   2> 9176 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10045 (1418077718810984448)]} 0 1
[junit4:junit4]   2> 9179 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[35 (-1418077718814130176)]} 0 0
[junit4:junit4]   2> 9183 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9183 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10045 (1418077718815178752)]} 0 3
[junit4:junit4]   2> 9186 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9186 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[35 (-1418077718817275904)]} 0 4
[junit4:junit4]   2> 9187 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10036 (-1418077718822518784)]} 0 0
[junit4:junit4]   2> 9190 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[43 (1418077718824615936)]} 0 1
[junit4:junit4]   2> 9193 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9194 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[10036 (-1418077718826713088)]} 0 4
[junit4:junit4]   2> 9197 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9198 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10046 (1418077718834053120)]} 0 0
[junit4:junit4]   2> 9198 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[43 (1418077718828810240)]} 0 5
[junit4:junit4]   2> 9204 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[44 (1418077718837198848)]} 0 3
[junit4:junit4]   2> 9206 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9207 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10046 (1418077718837198848)]} 0 6
[junit4:junit4]   2> 9210 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[10038 (-1418077718846636032)]} 0 0
[junit4:junit4]   2> 9215 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9216 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10038 (-1418077718849781760)]} 0 3
[junit4:junit4]   2> 9219 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10047 (1418077718855024640)]} 0 1
[junit4:junit4]   2> 9234 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=22 
[junit4:junit4]   2> 9234 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[44 (1418077718843490304)]} 0 27
[junit4:junit4]   2> 9236 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 9238 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10047 (1418077718858170368)]} 0 17
[junit4:junit4]   2> 9239 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[45 (1418077718877044736)]} 0 0
[junit4:junit4]   2> 9241 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10044 (-1418077718879141888)]} 0 0
[junit4:junit4]   2> 9245 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9246 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[45 (1418077718881239040)]} 0 3
[junit4:junit4]   2> 9248 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9249 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[36 (-1418077718887530496)]} 0 0
[junit4:junit4]   2> 9250 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[10044 (-1418077718882287616)]} 0 6
[junit4:junit4]   2> 9255 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10048 (1418077718892773376)]} 0 1
[junit4:junit4]   2> 9256 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9257 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[36 (-1418077718890676224)]} 0 5
[junit4:junit4]   2> 9260 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[46 (1418077718899064832)]} 0 0
[junit4:junit4]   2> 9262 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9262 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10048 (1418077718898016256)]} 0 3
[junit4:junit4]   2> 9267 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9268 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10045 (-1418077718907453440)]} 0 0
[junit4:junit4]   2> 9268 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[46 (1418077718902210560)]} 0 5
[junit4:junit4]   2> 9270 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[38 (-1418077718909550592)]} 0 0
[junit4:junit4]   2> 9275 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9275 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10045 (-1418077718910599168)]} 0 4
[junit4:junit4]   2> 9278 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9280 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10049 (1418077718918987776)]} 0 1
[junit4:junit4]   2> 9279 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[38 (-1418077718914793472)]} 0 5
[junit4:junit4]   2> 9284 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[47 (1418077718924230656)]} 0 1
[junit4:junit4]   2> 9287 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9288 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10049 (1418077718922133504)]} 0 6
[junit4:junit4]   2> 9300 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10050 (1418077718941007872)]} 0 0
[junit4:junit4]   2> 9303 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9305 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[47 (1418077718938910720)]} 0 7
[junit4:junit4]   2> 9308 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9308 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10050 (1418077718945202176)]} 0 4
[junit4:junit4]   2> 9310 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[44 (-1418077718951493632)]} 0 0
[junit4:junit4]   2> 9311 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[10048 (-1418077718952542208)]} 0 0
[junit4:junit4]   2> 9315 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9316 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[44 (-1418077718954639360)]} 0 3
[junit4:junit4]   2> 9316 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9317 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10048 (-1418077718955687936)]} 0 3
[junit4:junit4]   2> 9321 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[48 (1418077718961979392)]} 0 1
[junit4:junit4]   2> 9321 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10051 (1418077718961979393)]} 0 1
[junit4:junit4]   2> 9327 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9327 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[48 (1418077718965125120)]} 0 4
[junit4:junit4]   2> 9328 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9328 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10051 (1418077718967222272)]} 0 3
[junit4:junit4]   2> 9330 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[45 (-1418077718972465152)]} 0 0
[junit4:junit4]   2> 9334 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10052 (1418077718973513728)]} 0 3
[junit4:junit4]   2> 9336 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9337 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[45 (-1418077718976659456)]} 0 3
[junit4:junit4]   2> 9340 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[49 (1418077718981902336)]} 0 1
[junit4:junit4]   2> 9352 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 9353 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[49 (1418077718985048064)]} 0 11
[junit4:junit4]   2> 9370 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=27 
[junit4:junit4]   2> 9372 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10052 (1418077718980853760)]} 0 34
[junit4:junit4]   2> 9374 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[50 (1418077719018602496)]} 0 2
[junit4:junit4]   2> 9375 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10053 (1418077719019651072)]} 0 0
[junit4:junit4]   2> 9379 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9381 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[50 (1418077719021748224)]} 0 4
[junit4:junit4]   2> 9382 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9383 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10053 (1418077719022796800)]} 0 5
[junit4:junit4]   2> 9384 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[48 (-1418077719029088256)]} 0 0
[junit4:junit4]   2> 9387 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10054 (1418077719031185408)]} 0 1
[junit4:junit4]   2> 9390 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9392 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[48 (-1418077719032233984)]} 0 5
[junit4:junit4]   2> 9394 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9396 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10054 (1418077719036428288)]} 0 5
[junit4:junit4]   2> 9397 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[51 (1418077719042719744)]} 0 0
[junit4:junit4]   2> 9401 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10055 (1418077719045865472)]} 0 1
[junit4:junit4]   2> 9407 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9407 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[51 (1418077719046914048)]} 0 6
[junit4:junit4]   2> 9412 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9416 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[52 (1418077719059496960)]} 0 3
[junit4:junit4]   2> 9416 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10055 (1418077719055302656)]} 0 7
[junit4:junit4]   2> 9420 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10054 (-1418077719066836992)]} 0 0
[junit4:junit4]   2> 9424 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9425 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[52 (1418077719065788416)]} 0 6
[junit4:junit4]   2> 9429 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9431 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[53 (1418077719078371328)]} 0 0
[junit4:junit4]   2> 9433 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10054 (-1418077719073128448)]} 0 7
[junit4:junit4]   2> 9435 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10056 (1418077719082565632)]} 0 0
[junit4:junit4]   2> 9441 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9441 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[53 (1418077719084662784)]} 0 4
[junit4:junit4]   2> 9441 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9442 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10056 (1418077719085711360)]} 0 4
[junit4:junit4]   2> 9447 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[54 (1418077719092002816)]} 0 3
[junit4:junit4]   2> 9448 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10056 (-1418077719096197120)]} 0 0
[junit4:junit4]   2> 9454 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9455 T1338 C112 P64015 /update {wt=javabin&version=2} {delete=[10056 (-1418077719099342848)]} 0 4
[junit4:junit4]   2> 9459 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10057 (1418077719106682880)]} 0 1
[junit4:junit4]   2> 9467 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9468 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10057 (1418077719110877184)]} 0 6
[junit4:junit4]   2> 9471 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10057 (-1418077719120314368)]} 0 0
[junit4:junit4]   2> 9472 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=17 
[junit4:junit4]   2> 9472 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[54 (1418077719098294272)]} 0 22
[junit4:junit4]   2> 9475 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[55 (1418077719124508672)]} 0 0
[junit4:junit4]   2> 9476 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9477 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10057 (-1418077719123460096)]} 0 3
[junit4:junit4]   2> 9479 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10058 (1418077719128702976)]} 0 0
[junit4:junit4]   2> 9482 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9482 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[55 (1418077719127654400)]} 0 4
[junit4:junit4]   2> 9484 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9485 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10058 (1418077719131848704)]} 0 3
[junit4:junit4]   2> 9486 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[54 (-1418077719136043008)]} 0 0
[junit4:junit4]   2> 9487 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10059 (1418077719137091584)]} 0 0
[junit4:junit4]   2> 9492 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9493 T1338 C112 P64015 /update {wt=javabin&version=2} {delete=[54 (-1418077719139188736)]} 0 4
[junit4:junit4]   2> 9493 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9495 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10059 (1418077719140237312)]} 0 5
[junit4:junit4]   2> 9495 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[56 (1418077719145480192)]} 0 0
[junit4:junit4]   2> 9499 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10059 (-1418077719149674496)]} 0 1
[junit4:junit4]   2> 9502 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9502 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[56 (1418077719148625920)]} 0 4
[junit4:junit4]   2> 9503 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9504 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10059 (-1418077719151771648)]} 0 3
[junit4:junit4]   2> 9505 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[56 (-1418077719155965952)]} 0 0
[junit4:junit4]   2> 9506 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10060 (1418077719157014528)]} 0 0
[junit4:junit4]   2> 9511 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9512 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[56 (-1418077719160160256)]} 0 3
[junit4:junit4]   2> 9514 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9514 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10060 (1418077719161208832)]} 0 4
[junit4:junit4]   2> 9514 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[57 (1418077719165403136)]} 0 0
[junit4:junit4]   2> 9519 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10061 (1418077719170646016)]} 0 1
[junit4:junit4]   2> 9521 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9522 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[57 (1418077719169597440)]} 0 4
[junit4:junit4]   2> 9525 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[57 (-1418077719176937472)]} 0 0
[junit4:junit4]   2> 9527 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9528 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10061 (1418077719174840320)]} 0 5
[junit4:junit4]   2> 9533 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9534 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10060 (-1418077719186374656)]} 0 0
[junit4:junit4]   2> 9535 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[57 (-1418077719181131776)]} 0 6
[junit4:junit4]   2> 9538 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[58 (1418077719190568960)]} 0 0
[junit4:junit4]   2> 9544 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9545 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9546 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[58 (1418077719193714688)]} 0 5
[junit4:junit4]   2> 9547 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10060 (-1418077719189520384)]} 0 10
[junit4:junit4]   2> 9552 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[59 (1418077719201054720)]} 0 4
[junit4:junit4]   2> 9584 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10062 (1418077719237754880)]} 0 2
[junit4:junit4]   2> 9596 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 9596 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[59 (1418077719238803456)]} 0 12
[junit4:junit4]   2> 9600 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[59 (-1418077719255580672)]} 0 0
[junit4:junit4]   2> 9607 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9609 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[59 (-1418077719260823552)]} 0 4
[junit4:junit4]   2> 9611 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[60 (1418077719267115008)]} 0 0
[junit4:junit4]   2> 9615 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=24 
[junit4:junit4]   2> 9616 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10062 (1418077719242997760)]} 0 29
[junit4:junit4]   2> 9618 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9619 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[60 (1418077719270260736)]} 0 5
[junit4:junit4]   2> 9619 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10061 (-1418077719275503616)]} 0 0
[junit4:junit4]   2> 9623 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[61 (1418077719278649344)]} 0 1
[junit4:junit4]   2> 9624 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9625 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[10061 (-1418077719278649344)]} 0 3
[junit4:junit4]   2> 9629 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10063 (1418077719284940800)]} 0 1
[junit4:junit4]   2> 9630 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9631 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[61 (1418077719282843648)]} 0 5
[junit4:junit4]   2> 9635 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[60 (-1418077719292280832)]} 0 0
[junit4:junit4]   2> 9638 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9639 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10063 (1418077719290183680)]} 0 6
[junit4:junit4]   2> 9640 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9642 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[60 (-1418077719295426560)]} 0 4
[junit4:junit4]   2> 9642 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10064 (1418077719299620864)]} 0 0
[junit4:junit4]   2> 9644 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[62 (1418077719301718016)]} 0 0
[junit4:junit4]   2> 9651 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 9652 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10064 (1418077719302766592)]} 0 7
[junit4:junit4]   2> 9652 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9653 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[62 (1418077719304863744)]} 0 6
[junit4:junit4]   2> 9654 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10065 (1418077719312203776)]} 0 0
[junit4:junit4]   2> 9655 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[61 (-1418077719313252352)]} 0 0
[junit4:junit4]   2> 9661 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9662 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10065 (1418077719315349504)]} 0 5
[junit4:junit4]   2> 9663 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9664 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[61 (-1418077719317446656)]} 0 5
[junit4:junit4]   2> 9666 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10062 (-1418077719323738112)]} 0 1
[junit4:junit4]   2> 9668 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[63 (1418077719325835264)]} 0 1
[junit4:junit4]   2> 9672 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9672 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10062 (-1418077719328980992)]} 0 2
[junit4:junit4]   2> 9674 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9675 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[63 (1418077719331078144)]} 0 3
[junit4:junit4]   2> 9675 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10066 (1418077719334223872)]} 0 0
[junit4:junit4]   2> 9681 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9681 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[64 (1418077719336321024)]} 0 4
[junit4:junit4]   2> 9682 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10066 (1418077719337369600)]} 0 4
[junit4:junit4]   2> 9684 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10067 (1418077719343661056)]} 0 0
[junit4:junit4]   2> 9706 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9720 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=17 
[junit4:junit4]   2> 9720 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[64 (1418077719343661056)]} 0 36
[junit4:junit4]   2> 9721 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10067 (1418077719360438272)]} 0 21
[junit4:junit4]   2> 9724 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10068 (1418077719384555520)]} 0 1
[junit4:junit4]   2> 9725 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[65 (1418077719386652672)]} 0 0
[junit4:junit4]   2> 9732 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9732 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9734 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[65 (1418077719390846976)]} 0 5
[junit4:junit4]   2> 9735 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10068 (1418077719389798400)]} 0 8
[junit4:junit4]   2> 9737 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[62 (-1418077719399235584)]} 0 0
[junit4:junit4]   2> 9738 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[10063 (-1418077719400284160)]} 0 0
[junit4:junit4]   2> 9743 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9746 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[10063 (-1418077719403429889)]} 0 5
[junit4:junit4]   2> 9747 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9748 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10069 (1418077719410769920)]} 0 0
[junit4:junit4]   2> 9749 T1338 C112 P64015 /update {wt=javabin&version=2} {delete=[62 (-1418077719403429888)]} 0 8
[junit4:junit4]   2> 9754 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[66 (1418077719416012800)]} 0 1
[junit4:junit4]   2> 9755 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9756 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10069 (1418077719413915648)]} 0 5
[junit4:junit4]   2> 9759 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[10064 (-1418077719422304256)]} 0 0
[junit4:junit4]   2> 9761 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9762 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[66 (1418077719421255680)]} 0 4
[junit4:junit4]   2> 9765 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9767 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[67 (1418077719430692864)]} 0 0
[junit4:junit4]   2> 9768 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10064 (-1418077719425449984)]} 0 6
[junit4:junit4]   2> 9771 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10070 (1418077719433838592)]} 0 1
[junit4:junit4]   2> 9775 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9776 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9777 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10070 (1418077719436984320)]} 0 4
[junit4:junit4]   2> 9778 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[67 (1418077719435935744)]} 0 6
[junit4:junit4]   2> 9780 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[10065 (-1418077719444324352)]} 0 0
[junit4:junit4]   2> 9782 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[68 (1418077719445372928)]} 0 1
[junit4:junit4]   2> 9786 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9788 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9788 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10065 (-1418077719448518656)]} 0 4
[junit4:junit4]   2> 9789 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[68 (1418077719449567232)]} 0 4
[junit4:junit4]   2> 9793 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10071 (1418077719457955840)]} 0 0
[junit4:junit4]   2> 9795 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[63 (-1418077719460052992)]} 0 0
[junit4:junit4]   2> 9799 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9800 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9801 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[63 (-1418077719463198720)]} 0 3
[junit4:junit4]   2> 9802 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10071 (1418077719461101568)]} 0 6
[junit4:junit4]   2> 9807 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[69 (1418077719469490176)]} 0 3
[junit4:junit4]   2> 9808 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10072 (1418077719472635904)]} 0 1
[junit4:junit4]   2> 9830 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=14 
[junit4:junit4]   2> 9832 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9833 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10072 (1418077719477878784)]} 0 22
[junit4:junit4]   2> 9834 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[69 (1418077719481024512)]} 0 20
[junit4:junit4]   2> 9836 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10073 (1418077719503044608)]} 0 0
[junit4:junit4]   2> 9838 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[64 (-1418077719505141760)]} 0 0
[junit4:junit4]   2> 9844 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9853 T1338 C112 P64015 /update {wt=javabin&version=2} {delete=[64 (-1418077719509336064)]} 0 11
[junit4:junit4]   2> 9855 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9856 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10073 (1418077719508287488)]} 0 15
[junit4:junit4]   2> 9856 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[70 (1418077719524016128)]} 0 0
[junit4:junit4]   2> 9858 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10074 (1418077719526113280)]} 0 0
[junit4:junit4]   2> 9862 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9864 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[70 (1418077719527161856)]} 0 5
[junit4:junit4]   2> 9864 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9865 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10074 (1418077719529259008)]} 0 4
[junit4:junit4]   2> 9866 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[65 (-1418077719534501888)]} 0 0
[junit4:junit4]   2> 9868 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10075 (1418077719536599040)]} 0 0
[junit4:junit4]   2> 9871 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9871 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[65 (-1418077719537647616)]} 0 2
[junit4:junit4]   2> 9874 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9874 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10075 (1418077719539744768)]} 0 3
[junit4:junit4]   2> 9875 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[71 (1418077719542890496)]} 0 1
[junit4:junit4]   2> 9878 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10076 (1418077719546036224)]} 0 1
[junit4:junit4]   2> 9884 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9885 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[71 (1418077719547084800)]} 0 7
[junit4:junit4]   2> 9886 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9888 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10076 (1418077719551279104)]} 0 6
[junit4:junit4]   2> 9889 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[72 (1418077719557570560)]} 0 1
[junit4:junit4]   2> 9890 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10066 (-1418077719559667712)]} 0 0
[junit4:junit4]   2> 9895 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9895 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[72 (1418077719561764864)]} 0 4
[junit4:junit4]   2> 9895 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9896 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[10066 (-1418077719562813440)]} 0 3
[junit4:junit4]   2> 9898 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[73 (1418077719568056320)]} 0 0
[junit4:junit4]   2> 9905 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9906 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[73 (1418077719571202048)]} 0 5
[junit4:junit4]   2> 9908 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[74 (1418077719578542080)]} 0 0
[junit4:junit4]   2> 9913 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10077 (1418077719569104896)]} 0 14
[junit4:junit4]   2> 10037 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=118 
[junit4:junit4]   2> 10038 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10077 (1418077719586930688)]} 0 122
[junit4:junit4]   2> 10042 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10078 (1418077719718002688)]} 0 1
[junit4:junit4]   2> 10050 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10050 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10078 (1418077719722196992)]} 0 6
[junit4:junit4]   2> 10054 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10067 (-1418077719731634176)]} 0 0
[junit4:junit4]   2> 10061 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10061 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10067 (-1418077719735828480)]} 0 3
[junit4:junit4]   2> 10064 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10079 (1418077719742119936)]} 0 0
[junit4:junit4]   2> 10073 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10073 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10079 (1418077719747362816)]} 0 4
[junit4:junit4]   2> 10078 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10080 (1418077719756800000)]} 0 1
[junit4:junit4]   2> 10087 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10088 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10080 (1418077719760994304)]} 0 7
[junit4:junit4]   2> 10091 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10081 (1418077719770431488)]} 0 0
[junit4:junit4]   2> 10093 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10096 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[74 (1418077719581687808)]} 0 185
[junit4:junit4]   2> 10110 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10112 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10081 (1418077719775674368)]} 0 16
[junit4:junit4]   2> 10114 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[75 (1418077719794548736)]} 0 0
[junit4:junit4]   2> 10115 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10082 (1418077719795597312)]} 0 0
[junit4:junit4]   2> 10136 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10137 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[75 (1418077719797694464)]} 0 20
[junit4:junit4]   2> 10141 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10141 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[76 (1418077719821811712)]} 0 1
[junit4:junit4]   2> 10141 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10082 (1418077719815520256)]} 0 7
[junit4:junit4]   2> 10145 T1324 C113 P64006 /update {wt=javabin&version=2} {delete=[10068 (-1418077719827054592)]} 0 0
[junit4:junit4]   2> 10148 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10172 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[76 (1418077719824957440)]} 0 29
[junit4:junit4]   2> 10175 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[66 (-1418077719858511872)]} 0 0
[junit4:junit4]   2> 10176 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10177 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10068 (-1418077719857463296)]} 0 3
[junit4:junit4]   2> 10181 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10083 (1418077719863754752)]} 0 1
[junit4:junit4]   2> 10183 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10185 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[66 (-1418077719862706176)]} 0 6
[junit4:junit4]   2> 10194 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[77 (1418077719872143360)]} 0 6
[junit4:junit4]   2> 10200 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 10200 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10083 (1418077719870046208)]} 0 14
[junit4:junit4]   2> 10204 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[10070 (-1418077719888920576)]} 0 0
[junit4:junit4]   2> 10209 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10210 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10070 (-1418077719892066304)]} 0 3
[junit4:junit4]   2> 10214 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10084 (1418077719898357760)]} 0 1
[junit4:junit4]   2> 10224 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 10225 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10084 (1418077719901503488)]} 0 9
[junit4:junit4]   2> 10229 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10085 (1418077719915134976)]} 0 0
[junit4:junit4]   2> 10256 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 10257 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10085 (1418077719918280704)]} 0 25
[junit4:junit4]   2> 10261 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10086 (1418077719947640832)]} 0 1
[junit4:junit4]   2> 10273 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 10274 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10086 (1418077719950786560)]} 0 11
[junit4:junit4]   2> 10276 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10087 (1418077719964418048)]} 0 0
[junit4:junit4]   2> 10285 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10286 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10087 (1418077719967563776)]} 0 7
[junit4:junit4]   2> 10289 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10088 (1418077719977000960)]} 0 1
[junit4:junit4]   2> 10297 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10297 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10088 (1418077719981195264)]} 0 5
[junit4:junit4]   2> 10301 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10089 (1418077719990632448)]} 0 0
[junit4:junit4]   2> 10308 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10308 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[10089 (1418077719992729600)]} 0 5
[junit4:junit4]   2> 10330 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10090 (1418077720021041152)]} 0 1
[junit4:junit4]   2> 10356 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=153 
[junit4:junit4]   2> 10359 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=22 
[junit4:junit4]   2> 10359 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10090 (1418077720024186880)]} 0 26
[junit4:junit4]   2> 10360 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[77 (1418077719880531968)]} 0 164
[junit4:junit4]   2> 10363 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[78 (1418077720054595584)]} 0 1
[junit4:junit4]   2> 10363 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[10076 (-1418077720055644160)]} 0 0
[junit4:junit4]   2> 10371 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10372 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10372 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10076 (-1418077720059838464)]} 0 5
[junit4:junit4]   2> 10374 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[78 (1418077720059838465)]} 0 7
[junit4:junit4]   2> 10377 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[67 (-1418077720070324224)]} 0 0
[junit4:junit4]   2> 10378 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10091 (1418077720070324225)]} 0 1
[junit4:junit4]   2> 10383 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10385 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[67 (-1418077720073469953)]} 0 5
[junit4:junit4]   2> 10385 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10386 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10091 (1418077720073469952)]} 0 6
[junit4:junit4]   2> 10390 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10092 (1418077720082907136)]} 0 1
[junit4:junit4]   2> 10393 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[79 (1418077720080809984)]} 0 6
[junit4:junit4]   2> 10411 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 10412 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10092 (1418077720089198592)]} 0 17
[junit4:junit4]   2> 10417 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10093 (1418077720111218688)]} 0 1
[junit4:junit4]   2> 10428 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 10430 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10093 (1418077720115412992)]} 0 10
[junit4:junit4]   2> 10434 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10077 (-1418077720130093056)]} 0 0
[junit4:junit4]   2> 10440 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=41 
[junit4:junit4]   2> 10441 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[79 (1418077720090247168)]} 0 45
[junit4:junit4]   2> 10442 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10443 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[10077 (-1418077720136384512)]} 0 3
[junit4:junit4]   2> 10444 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[80 (1418077720140578816)]} 0 0
[junit4:junit4]   2> 10448 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10094 (1418077720144773120)]} 0 0
[junit4:junit4]   2> 10453 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10453 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[80 (1418077720144773120)]} 0 5
[junit4:junit4]   2> 10458 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[81 (1418077720154210304)]} 0 1
[junit4:junit4]   2> 10459 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10459 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10094 (1418077720151064576)]} 0 5
[junit4:junit4]   2> 10462 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10079 (-1418077720159453184)]} 0 0
[junit4:junit4]   2> 10463 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10465 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[81 (1418077720157356032)]} 0 5
[junit4:junit4]   2> 10468 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10468 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[82 (1418077720165744640)]} 0 0
[junit4:junit4]   2> 10469 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[10079 (-1418077720163647488)]} 0 3
[junit4:junit4]   2> 10472 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10095 (1418077720169938944)]} 0 0
[junit4:junit4]   2> 10475 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10476 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[82 (1418077720168890368)]} 0 5
[junit4:junit4]   2> 10479 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[68 (-1418077720177278976)]} 0 0
[junit4:junit4]   2> 10481 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10481 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10095 (1418077720175181824)]} 0 4
[junit4:junit4]   2> 10486 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10096 (1418077720183570432)]} 0 1
[junit4:junit4]   2> 10486 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10487 T1337 C112 P64015 /update {wt=javabin&version=2} {delete=[68 (-1418077720181473280)]} 0 4
[junit4:junit4]   2> 10492 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[83 (1418077720188813312)]} 0 2
[junit4:junit4]   2> 10494 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10494 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10096 (1418077720187764736)]} 0 5
[junit4:junit4]   2> 10497 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10080 (-1418077720196153344)]} 0 0
[junit4:junit4]   2> 10498 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10501 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[83 (1418077720194056192)]} 0 6
[junit4:junit4]   2> 10503 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10504 T1336 C112 P64015 /update {wt=javabin&version=2} {delete=[10080 (-1418077720200347648)]} 0 3
[junit4:junit4]   2> 10504 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[70 (-1418077720203493376)]} 0 0
[junit4:junit4]   2> 10511 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10511 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[70 (-1418077720206639104)]} 0 4
[junit4:junit4]   2> 10511 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10097 (1418077720206639104)]} 0 4
[junit4:junit4]   2> 10514 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[84 (1418077720213979136)]} 0 0
[junit4:junit4]   2> 10524 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10525 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[84 (1418077720217124864)]} 0 8
[junit4:junit4]   2> 10530 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[85 (1418077720229707776)]} 0 1
[junit4:junit4]   2> 10535 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10537 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[85 (1418077720232853504)]} 0 5
[junit4:junit4]   2> 10539 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=19 
[junit4:junit4]   2> 10539 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[86 (1418077720240193536)]} 0 0
[junit4:junit4]   2> 10540 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10097 (1418077720213979136)]} 0 26
[junit4:junit4]   2> 10543 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[10084 (-1418077720244387840)]} 0 0
[junit4:junit4]   2> 10546 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10548 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[86 (1418077720243339264)]} 0 6
[junit4:junit4]   2> 10551 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10551 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[87 (1418077720252776448)]} 0 0
[junit4:junit4]   2> 10552 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[10084 (-1418077720249630720)]} 0 4
[junit4:junit4]   2> 10556 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10098 (1418077720258019328)]} 0 0
[junit4:junit4]   2> 10558 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10559 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[87 (1418077720256970752)]} 0 4
[junit4:junit4]   2> 10561 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[88 (1418077720263262208)]} 0 0
[junit4:junit4]   2> 10562 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10563 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10098 (1418077720261165056)]} 0 4
[junit4:junit4]   2> 10566 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[10099 (1418077720268505088)]} 0 0
[junit4:junit4]   2> 10568 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10569 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[88 (1418077720267456512)]} 0 4
[junit4:junit4]   2> 10572 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[89 (1418077720273747968)]} 0 1
[junit4:junit4]   2> 10592 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10593 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[10099 (1418077720272699392)]} 0 24
[junit4:junit4]   2> 10595 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10595 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10100 (1418077720298913792)]} 0 0
[junit4:junit4]   2> 10596 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[89 (1418077720295768064)]} 0 4
[junit4:junit4]   2> 10603 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[90 (1418077720305205248)]} 0 2
[junit4:junit4]   2> 10603 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10605 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[10100 (1418077720302059520)]} 0 7
[junit4:junit4]   2> 10608 T1321 C113 P64006 /update {wt=javabin&version=2} {delete=[10085 (-1418077720312545280)]} 0 0
[junit4:junit4]   2> 10614 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10615 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10085 (-1418077720316739584)]} 0 3
[junit4:junit4]   2> 10617 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10101 (1418077720321982464)]} 0 0
[junit4:junit4]   2> 10625 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=15 
[junit4:junit4]   2> 10626 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[90 (1418077720309399552)]} 0 21
[junit4:junit4]   2> 10626 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10628 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10101 (1418077720327225344)]} 0 6
[junit4:junit4]   2> 10630 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[76 (-1418077720335613952)]} 0 0
[junit4:junit4]   2> 10632 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10102 (1418077720337711104)]} 0 0
[junit4:junit4]   2> 10637 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10638 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[76 (-1418077720338759680)]} 0 5
[junit4:junit4]   2> 10639 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10640 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10102 (1418077720340856832)]} 0 5
[junit4:junit4]   2> 10641 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[91 (1418077720346099712)]} 0 1
[junit4:junit4]   2> 10642 T1320 C113 P64006 /update {wt=javabin&version=2} {delete=[10087 (-1418077720348196864)]} 0 0
[junit4:junit4]   2> 10647 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10648 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[91 (1418077720349245440)]} 0 5
[junit4:junit4]   2> 10648 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10649 T1334 C112 P64015 /update {wt=javabin&version=2} {delete=[10087 (-1418077720352391168)]} 0 3
[junit4:junit4]   2> 10651 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[92 (1418077720356585472)]} 0 1
[junit4:junit4]   2> 10653 T1322 C113 P64006 /update {wt=javabin&version=2} {add=[10103 (1418077720358682624)]} 0 1
[junit4:junit4]   2> 10657 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10657 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[92 (1418077720359731200)]} 0 4
[junit4:junit4]   2> 10660 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10661 T1323 C113 P64006 /update {wt=javabin&version=2} {add=[93 (1418077720367071232)]} 0 1
[junit4:junit4]   2> 10661 T1338 C112 P64015 /update {wt=javabin&version=2} {add=[10103 (1418077720362876928)]} 0 5
[junit4:junit4]   2> 10667 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10104 (1418077720374411264)]} 0 1
[junit4:junit4]   2> 10668 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10668 T1340 C112 P64015 /update {wt=javabin&version=2} {add=[93 (1418077720372314112)]} 0 4
[junit4:junit4]   2> 10671 T1319 C113 P64006 /update {wt=javabin&version=2} {delete=[77 (-1418077720378605568)]} 0 0
[junit4:junit4]   2> 10675 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10676 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10104 (1418077720377556992)]} 0 6
[junit4:junit4]   2> 10677 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10678 T1339 C112 P64015 /update {wt=javabin&version=2} {delete=[77 (-1418077720382799872)]} 0 3
[junit4:junit4]   2> 10679 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[10105 (1418077720386994176)]} 0 0
[junit4:junit4]   2> 10681 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[94 (1418077720389091328)]} 0 0
[junit4:junit4]   2> 10686 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10687 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10105 (1418077720391188480)]} 0 4
[junit4:junit4]   2> 10689 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10689 T1322 C113 P64006 /update {wt=javabin&version=2} {delete=[10088 (-1418077720397479936)]} 0 0
[junit4:junit4]   2> 10689 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[94 (1418077720393285632)]} 0 4
[junit4:junit4]   2> 10694 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[79 (-1418077720402722816)]} 0 0
[junit4:junit4]   2> 10696 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10697 T1338 C112 P64015 /update {wt=javabin&version=2} {delete=[10088 (-1418077720402722816)]} 0 3
[junit4:junit4]   2> 10701 T1352 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10702 T1340 C112 P64015 /update {wt=javabin&version=2} {delete=[79 (-1418077720405868544)]} 0 5
[junit4:junit4]   2> 10704 T1319 C113 P64006 /update {wt=javabin&version=2} {add=[10106 (1418077720410062848)]} 0 3
[junit4:junit4]   2> 10705 T1320 C113 P64006 /update {wt=javabin&version=2} {add=[95 (1418077720413208576)]} 0 1
[junit4:junit4]   2> 10717 T1348 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10729 T1336 C112 P64015 /update {wt=javabin&version=2} {add=[10106 (1418077720417402880)]} 0 21
[junit4:junit4]   2> 10731 T1347 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=17 
[junit4:junit4]   2> 10732 T1339 C112 P64015 /update {wt=javabin&version=2} {add=[95 (1418077720419500032)]} 0 22
[junit4:junit4]   2> 10734 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[10107 (1418077720443617280)]} 0 1
[junit4:junit4]   2> 10737 T1321 C113 P64006 /update {wt=javabin&version=2} {add=[96 (1418077720447811584)]} 0 3
[junit4:junit4]   2> 10746 T1349 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10746 T1334 C112 P64015 /update {wt=javabin&version=2} {add=[10107 (1418077720449908736)]} 0 7
[junit4:junit4]   2> 10747 T1350 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10748 T1337 C112 P64015 /update {wt=javabin&version=2} {add=[96 (1418077720452005888)]} 0 7
[junit4:junit4]   2> 10751 T1323 C113 P64006 /update {wt=javabin&version=2} {delete=[80 (-1418077720462491649)]} 0 0
[junit4:junit4]   2> 10751 T1324 C113 P64006 /update {wt=javabin&version=2} {add=[10108 (1418077720462491648)]} 0 0
[junit4:junit4]   2> 10756 T1351 C111 P64021 REQ /update {distrib.from=http://127.0.0.1:64015/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10757 T1338 C112 P64015 /update {wt=javabin&version=2} {delete=[80 (-141807772046458

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

collections/collection1/leaders/shard1
[junit4:junit4]   2> 26508 T1393 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard1/election/88629856825245702-127.0.0.1:64021_solr_collection1-n_0000000003
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> 	at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:734)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:144)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:141)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:63)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:141)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:63)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:243)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:155)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:99)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:128)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 26509 T1393 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 26511 T1393 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 26511 T1393 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 26511 T1393 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 26511 T1393 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 26512 T1393 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 26576 T1306 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> 26579 T1306 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {range_facet_l=PostingsFormat(name=NestedPulsing), text=Pulsing41(freqCutoff=2 minBlockSize=2 maxBlockSize=51), _version_=PostingsFormat(name=NestedPulsing), rnd_b=Lucene41(blocksize=128), intDefault=PostingsFormat(name=NestedPulsing), timestamp=PostingsFormat(name=NestedPulsing), id=Lucene41(blocksize=128), a_t=PostingsFormat(name=NestedPulsing), range_facet_sl=Pulsing41(freqCutoff=2 minBlockSize=2 maxBlockSize=51), range_facet_si=PostingsFormat(name=Direct), other_tl1=PostingsFormat(name=NestedPulsing), multiDefault=PostingsFormat(name=Direct), a_si=PostingsFormat(name=Direct)}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=sr_BA_#Latn, timezone=Canada/Atlantic
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_09 (32-bit)/cpus=2,threads=2,free=160466560,total=287707136
[junit4:junit4]   2> NOTE: All tests run in this JVM: [LukeRequestHandlerTest, CacheHeaderTest, TestDFRSimilarityFactory, DefaultValueUpdateProcessorTest, TestSuggestSpellingConverter, TestDocumentBuilder, TestCodecSupport, LoggingHandlerTest, TestCSVResponseWriter, NoCacheHeaderTest, TestSolrXMLSerializer, AutoCommitTest, TestGroupingSearch, TestPluginEnable, TestFaceting, TestDistributedSearch, AlternateDirectoryTest, TestQueryUtils, BasicZkTest, TestSolrQueryParser, TestLMDirichletSimilarityFactory, PluginInfoTest, TestStressRecovery, TestBinaryField, TestPHPSerializedResponseWriter, TestJmxIntegration, UUIDFieldTest, TestRangeQuery, TestWordDelimiterFilterFactory, IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, TestReload, MBeansHandlerTest, TestAnalyzedSuggestions, TestPerFieldSimilarity, ResponseLogComponentTest, URLClassifyProcessorTest, AnalysisAfterCoreReloadTest, TestMultiCoreConfBootstrap, TestCollationField, TestJoin, UniqFieldsUpdateProcessorFactoryTest, PrimUtilsTest, SpellCheckCollatorTest, JsonLoaderTest, MinimalSchemaTest, PingRequestHandlerTest, TestDefaultSimilarityFactory, EchoParamsTest, OverseerTest, SampleTest, OpenExchangeRatesOrgProviderTest, HighlighterTest, TermVectorComponentDistributedTest, TestFunctionQuery, TestLuceneMatchVersion, TestRandomFaceting, TestRecovery, TestConfig, TestTrie, TestQuerySenderListener, TestSurroundQueryParser, TestPhraseSuggestions, TestFastWriter, TestValueSourceCache, TestStressVersions, SpellCheckComponentTest, XmlUpdateRequestHandlerTest, ClusterStateTest, TestBM25SimilarityFactory, PreAnalyzedFieldTest, SuggesterWFSTTest, CSVRequestHandlerTest, TestSolrDeletionPolicy2, BadComponentTest, SimplePostToolTest, DocumentAnalysisRequestHandlerTest, BasicDistributedZk2Test, MoreLikeThisHandlerTest, TestJmxMonitoredMap, TestWriterPerf, JSONWriterTest, TestElisionMultitermQuery, TestLFUCache, ResourceLoaderTest, RecoveryZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.seed=7C61925BB1291949 -Dtests.slow=true -Dtests.locale=sr_BA_#Latn -Dtests.timezone=Canada/Atlantic -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   0.00s | RecoveryZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1356, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([7C61925BB1291949]:0)
[junit4:junit4]    > 	at java.lang.Thread.getStackTrace(Thread.java:1567)
[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@7cd6df lockFactory=org.apache.lucene.store.SimpleFSLockFactory@d6fe48)
[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:722)
[junit4:junit4] Completed in 26.66s, 1 test, 1 error <<< FAILURES!

[...truncated 453 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:335: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:178: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\module-build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1163: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:827: There were test failures: 233 suites, 970 tests, 1 suite-level error, 12 ignored (7 assumptions)

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



Mime
View raw message