lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-trunk-java7 - Build # 3324 - Failure
Date Sat, 20 Oct 2012 04:24:34 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-java7/3324/

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

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

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




Build Log:
[...truncated 8686 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 4 T1160 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1350706374601
[junit4:junit4]   2> 5 T1160 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 9 T1161 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 9 T1161 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 10 T1161 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 107 T1160 oasc.ZkTestServer.run start zk server on port:30627
[junit4:junit4]   2> 107 T1160 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:30627 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7aac44b5
[junit4:junit4]   2> 108 T1166 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:30627
[junit4:junit4]   2> 108 T1160 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 109 T1166 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:30627, initiating session
[junit4:junit4]   2> 109 T1162 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:46396
[junit4:junit4]   2> 110 T1162 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:46396
[junit4:junit4]   2> 110 T1164 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 114 T1164 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a7c5fa7cf0000 with negotiated timeout 10000 for client /140.211.11.196:46396
[junit4:junit4]   2> 114 T1166 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:30627, sessionid = 0x13a7c5fa7cf0000, negotiated timeout = 10000
[junit4:junit4]   2> 114 T1167 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7aac44b5 name:ZooKeeperConnection Watcher:127.0.0.1:30627 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 115 T1160 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 115 T1160 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 135 T1165 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a7c5fa7cf0000
[junit4:junit4]   2> 153 T1167 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 153 T1160 oaz.ZooKeeper.close Session: 0x13a7c5fa7cf0000 closed
[junit4:junit4]   2> 154 T1162 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:46396 which had sessionid 0x13a7c5fa7cf0000
[junit4:junit4]   2> 154 T1160 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:30627/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@bb28d3
[junit4:junit4]   2> 155 T1168 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:30627
[junit4:junit4]   2> 155 T1160 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 156 T1168 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:30627, initiating session
[junit4:junit4]   2> 156 T1162 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:34721
[junit4:junit4]   2> 157 T1162 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:34721
[junit4:junit4]   2> 158 T1164 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a7c5fa7cf0001 with negotiated timeout 10000 for client /140.211.11.196:34721
[junit4:junit4]   2> 158 T1168 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:30627, sessionid = 0x13a7c5fa7cf0001, negotiated timeout = 10000
[junit4:junit4]   2> 158 T1169 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bb28d3 name:ZooKeeperConnection Watcher:127.0.0.1:30627/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 159 T1160 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 159 T1160 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 182 T1160 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 185 T1160 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 195 T1160 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 202 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 203 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 414 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 416 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 539 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 540 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 578 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 579 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 589 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 590 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 600 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 601 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 671 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 672 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 703 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 704 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 755 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 756 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 801 T1160 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 802 T1160 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 825 T1165 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a7c5fa7cf0001
[junit4:junit4]   2> 878 T1169 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 878 T1162 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:34721 which had sessionid 0x13a7c5fa7cf0001
[junit4:junit4]   2> 878 T1160 oaz.ZooKeeper.close Session: 0x13a7c5fa7cf0001 closed
[junit4:junit4]   2> 1166 T1160 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1171 T1160 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:20503
[junit4:junit4]   2> 1172 T1160 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1172 T1160 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475
[junit4:junit4]   2> 1173 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/'
[junit4:junit4]   2> 1219 T1160 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1219 T1160 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1220 T1160 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475
[junit4:junit4]   2> 1220 T1160 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/solr.xml
[junit4:junit4]   2> 1221 T1160 oasc.CoreContainer.<init> New CoreContainer 2144956808
[junit4:junit4]   2> 1221 T1160 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/'
[junit4:junit4]   2> 1222 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/'
[junit4:junit4]   2> 1289 T1160 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1323 T1160 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:30627/solr
[junit4:junit4]   2> 1324 T1160 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:30627/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@72537f9b
[junit4:junit4]   2> 1325 T1179 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:30627
[junit4:junit4]   2> 1325 T1160 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1327 T1179 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:30627, initiating session
[junit4:junit4]   2> 1327 T1162 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:40291
[junit4:junit4]   2> 1327 T1162 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:40291
[junit4:junit4]   2> 1342 T1164 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a7c5fa7cf0002 with negotiated timeout 5000 for client /140.211.11.196:40291
[junit4:junit4]   2> 1343 T1179 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:30627, sessionid = 0x13a7c5fa7cf0002, negotiated timeout = 5000
[junit4:junit4]   2> 1343 T1180 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72537f9b name:ZooKeeperConnection Watcher:127.0.0.1:30627/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1343 T1160 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1370 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1382 T1160 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 1384 T1160 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:20503_solr
[junit4:junit4]   2> 1385 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:20503_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:20503_solr
[junit4:junit4]   2> 1398 T1160 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:20503_solr
[junit4:junit4]   2> 1402 T1160 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1402 T1160 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1403 T1160 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1403 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1404 T1160 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1404 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1405 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1405 T1160 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1406 T1160 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1406 T1160 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1428 T1160 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 1455 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 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> 1475 T1160 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 1477 T1160 oasc.Overseer.start Overseer (id=88519892966244354-127.0.0.1:20503_solr-n_0000000000) starting
[junit4:junit4]   2> 1478 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1492 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1495 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1504 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1516 T1182 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 1516 T1160 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 1542 T1160 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1544 T1181 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 1556 T1160 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/collection1
[junit4:junit4]   2> 1557 T1160 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 1558 T1160 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1558 T1160 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 1560 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/collection1/'
[junit4:junit4]   2> 1561 T1160 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1562 T1160 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/collection1/lib/README' to classloader
[junit4:junit4]   2> 1670 T1160 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1833 T1160 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1934 T1160 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1950 T1160 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2859 T1160 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2870 T1160 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2880 T1160 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2904 T1160 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2910 T1160 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2915 T1160 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2917 T1160 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2918 T1160 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1350706375475/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1350706374600/control/data/
[junit4:junit4]   2> 2918 T1160 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@136d8cda
[junit4:junit4]   2> 2919 T1160 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1350706374600/control/data/index/
[junit4:junit4]   2> 2920 T1160 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1350706374600/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2922 T1160 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1350706374600/control/data/index forceNew:false
[junit4:junit4]   2> 3001 T1160 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350706374600/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2ef451df; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3002 T1160 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3012 T1160 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3014 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3015 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3016 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3016 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3017 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3018 T1160 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3018 T1160 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3019 T1160 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3020 T1160 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3021 T1160 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3021 T1160 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3022 T1160 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3023 T1160 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3023 T1160 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3024 T1160 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3025 T1160 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3026 T1160 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3026 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3027 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3028 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3028 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3029 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3030 T1160 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3031 T1160 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3031 T1160 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3044 T1160 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3049 T1160 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3051 T1160 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> 3066 T1160 oass.SolrIndexSearcher.<init> Opening Searcher@337d0ea3 main
[junit4:junit4]   2> 3067 T1160 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3067 T1160 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3068 T1160 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3174 T1160 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3318 T1183 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@337d0ea3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3322 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x59 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 3387 T1160 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3388 T1160 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:20503/solr shardId:control_shard
[junit4:junit4]   2> 3389 T1160 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 3445 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:delete cxid:0x68 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> 3459 T1160 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3460 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x69 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3553 T1181 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3554 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x70 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 3595 T1180 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3987 T1160 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3988 T1160 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3989 T1160 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:20503/solr/collection1/
[junit4:junit4]   2> 3989 T1160 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3990 T1160 oasc.SyncStrategy.syncToMe http://127.0.0.1:20503/solr/collection1/ has no replicas
[junit4:junit4]   2> 3990 T1160 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:20503/solr/collection1/
[junit4:junit4]   2> 3991 T1160 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 4017 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0002 type:create cxid:0x8e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4110 T1181 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4155 T1180 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4181 T1160 oasc.ZkController.register We are http://127.0.0.1:20503/solr/collection1/ and leader is http://127.0.0.1:20503/solr/collection1/
[junit4:junit4]   2> 4181 T1160 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:20503/solr
[junit4:junit4]   2> 4182 T1160 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4198 T1160 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4200 T1160 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4200 T1160 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4201 T1160 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4418 T1160 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4421 T1160 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:19349
[junit4:junit4]   2> 4422 T1160 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4423 T1160 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814
[junit4:junit4]   2> 4423 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/'
[junit4:junit4]   2> 4542 T1160 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4542 T1160 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4543 T1160 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814
[junit4:junit4]   2> 4543 T1160 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/solr.xml
[junit4:junit4]   2> 4543 T1160 oasc.CoreContainer.<init> New CoreContainer 1712640850
[junit4:junit4]   2> 4544 T1160 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/'
[junit4:junit4]   2> 4544 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/'
[junit4:junit4]   2> 4566 T1160 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4603 T1160 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:30627/solr
[junit4:junit4]   2> 4604 T1160 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:30627/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@2de9c9cb
[junit4:junit4]   2> 4605 T1193 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:30627
[junit4:junit4]   2> 4605 T1160 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4606 T1193 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:30627, initiating session
[junit4:junit4]   2> 4606 T1162 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:24475
[junit4:junit4]   2> 4607 T1162 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:24475
[junit4:junit4]   2> 4617 T1164 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a7c5fa7cf0003 with negotiated timeout 5000 for client /140.211.11.196:24475
[junit4:junit4]   2> 4618 T1193 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:30627, sessionid = 0x13a7c5fa7cf0003, negotiated timeout = 5000
[junit4:junit4]   2> 4618 T1194 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2de9c9cb name:ZooKeeperConnection Watcher:127.0.0.1:30627/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4618 T1160 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4621 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4729 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4806 T1181 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4806 T1160 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:19349_solr
[junit4:junit4]   2> 4807 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:19349_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:19349_solr
[junit4:junit4]   2> 4836 T1160 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:19349_solr
[junit4:junit4]   2> 4864 T1180 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4865 T1160 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4865 T1160 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4866 T1160 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4866 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4866 T1160 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4867 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4867 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4867 T1160 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4868 T1160 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4868 T1160 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4882 T1180 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4885 T1160 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4891 T1160 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/collection1
[junit4:junit4]   2> 4891 T1160 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4892 T1160 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4892 T1160 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4893 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/collection1/'
[junit4:junit4]   2> 4894 T1160 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4895 T1160 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/collection1/lib/README' to classloader
[junit4:junit4]   2> 4974 T1160 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5072 T1160 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5173 T1160 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5194 T1160 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6329 T1160 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6342 T1160 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6349 T1160 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6370 T1160 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6380 T1160 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6387 T1160 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6389 T1160 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6390 T1160 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1350706378814/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty1/
[junit4:junit4]   2> 6391 T1160 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@136d8cda
[junit4:junit4]   2> 6393 T1160 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty1/index/
[junit4:junit4]   2> 6395 T1160 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6396 T1160 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty1/index forceNew:false
[junit4:junit4]   2> 6412 T1160 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@44293e3f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6413 T1160 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6415 T1160 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6419 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6420 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6421 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6421 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6422 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6423 T1160 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6423 T1160 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6424 T1160 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6425 T1160 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6426 T1160 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6427 T1160 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6428 T1160 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6428 T1160 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6429 T1160 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6430 T1160 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6430 T1160 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6431 T1160 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6432 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6433 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6434 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6435 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6436 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6437 T1160 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6437 T1160 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6438 T1160 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6459 T1160 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6465 T1160 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6466 T1160 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> 6470 T1160 oass.SolrIndexSearcher.<init> Opening Searcher@33e2e6f main
[junit4:junit4]   2> 6472 T1160 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6472 T1160 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6473 T1160 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6501 T1160 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6612 T1195 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@33e2e6f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6890 T1181 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6944 T1194 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6944 T1180 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7143 T1160 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7143 T1160 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:19349/solr shardId:shard1
[junit4:junit4]   2> 7145 T1160 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 7219 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0003 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 7229 T1160 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7230 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0003 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7251 T1160 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7252 T1160 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7252 T1160 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:19349/solr/collection1/
[junit4:junit4]   2> 7252 T1160 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7253 T1160 oasc.SyncStrategy.syncToMe http://127.0.0.1:19349/solr/collection1/ has no replicas
[junit4:junit4]   2> 7253 T1160 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:19349/solr/collection1/
[junit4:junit4]   2> 7253 T1160 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 7263 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7452 T1181 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7524 T1194 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7524 T1180 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7548 T1160 oasc.ZkController.register We are http://127.0.0.1:19349/solr/collection1/ and leader is http://127.0.0.1:19349/solr/collection1/
[junit4:junit4]   2> 7548 T1160 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:19349/solr
[junit4:junit4]   2> 7549 T1160 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7569 T1160 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7570 T1160 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 7571 T1160 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7571 T1160 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7845 T1160 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 7848 T1160 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:53786
[junit4:junit4]   2> 7849 T1160 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7850 T1160 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185
[junit4:junit4]   2> 7850 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/'
[junit4:junit4]   2> 7959 T1160 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7959 T1160 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7960 T1160 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185
[junit4:junit4]   2> 7960 T1160 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/solr.xml
[junit4:junit4]   2> 7961 T1160 oasc.CoreContainer.<init> New CoreContainer 1424643568
[junit4:junit4]   2> 7961 T1160 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/'
[junit4:junit4]   2> 7962 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/'
[junit4:junit4]   2> 8030 T1160 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8049 T1160 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:30627/solr
[junit4:junit4]   2> 8050 T1160 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:30627/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@339f3285
[junit4:junit4]   2> 8051 T1205 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:30627
[junit4:junit4]   2> 8051 T1160 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8054 T1205 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:30627, initiating session
[junit4:junit4]   2> 8054 T1162 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:45396
[junit4:junit4]   2> 8055 T1162 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:45396
[junit4:junit4]   2> 8057 T1164 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a7c5fa7cf0004 with negotiated timeout 5000 for client /140.211.11.196:45396
[junit4:junit4]   2> 8057 T1205 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:30627, sessionid = 0x13a7c5fa7cf0004, negotiated timeout = 5000
[junit4:junit4]   2> 8058 T1206 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@339f3285 name:ZooKeeperConnection Watcher:127.0.0.1:30627/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8058 T1160 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8059 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8093 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8094 T1181 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8094 T1160 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53786_solr
[junit4:junit4]   2> 8095 T1165 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a7c5fa7cf0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:53786_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:53786_solr
[junit4:junit4]   2> 8126 T1160 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53786_solr
[junit4:junit4]   2> 8146 T1180 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8146 T1194 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8147 T1194 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8148 T1160 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8148 T1160 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8149 T1160 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8149 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8150 T1160 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8150 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8151 T1160 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8151 T1160 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8152 T1160 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8152 T1160 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8157 T1180 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8177 T1160 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8184 T1160 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/collection1
[junit4:junit4]   2> 8185 T1160 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8186 T1160 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8187 T1160 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8188 T1160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/collection1/'
[junit4:junit4]   2> 8190 T1160 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/collection1/lib/README' to classloader
[junit4:junit4]   2> 8190 T1160 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8278 T1160 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 8381 T1160 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8482 T1160 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8491 T1160 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9096 T1160 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9103 T1160 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9106 T1160 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9118 T1160 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9122 T1160 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9126 T1160 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9127 T1160 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9128 T1160 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1350706382185/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty2/
[junit4:junit4]   2> 9129 T1160 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@136d8cda
[junit4:junit4]   2> 9130 T1160 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty2/index/
[junit4:junit4]   2> 9130 T1160 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9131 T1160 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty2/index forceNew:false
[junit4:junit4]   2> 9160 T1160 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1dc706b8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9161 T1160 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 9162 T1160 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 9164 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9165 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 9165 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9165 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 9166 T1160 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9166 T1160 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9167 T1160 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9168 T1160 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9168 T1160 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9169 T1160 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 9169 T1160 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 9170 T1160 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9171 T1160 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 9171 T1160 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 9171 T1160 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 9172 T1160 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9172 T1160 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9173 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9174 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9174 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9175 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9175 T1160 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9176 T1160 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9176 T1160 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 9177 T1160 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 9186 T1160 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9189 T1160 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 9190 T1160 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> 9193 T1160 oass.SolrIndexSearcher.<init> Opening Searcher@1c788d2 main
[junit4:junit4]   2> 9194 T1160 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9195 T1160 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9195 T1160 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 9233 T1160 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 9383 T1207 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c788d2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9662 T1181 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9698 T1206 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9698 T1180 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9698 T1194 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9891 T1160 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 9892 T1160 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:53786/solr shardId:shard1
[junit4:junit4]   2> 9906 T1160 oasc.ZkController.register We are http://127.0.0.1:53786/solr/collection1/ and leader is http://127.0.0.1:19349/solr/collection1/
[junit4:junit4]   2> 9907 T1160 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53786/solr
[junit4:junit4]   2> 9907 T1160 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 9908 T1160 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C71 name=collection1 org.apache.solr.core.SolrCore@243386d url=http://127.0.0.1:53786/solr/collection1 node=127.0.0.1:53786_solr
[junit4:junit4]   2> 9909 T1208 C71 P53786 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 9910 T1208 C71 P53786 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 9910 T1160 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9982 T1208 C71 P53786 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:19349/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 9983 T1208 C71 P53786 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:53786/solr START replicas=[http://127.0.0.1:19349/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 9983 T1160 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 9983 T1208 C71 P53786 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 9983 T1160 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 9984 T1208 C71 P53786 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 9985 T1208 C71 P53786 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 9985 T1160 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9985 T1208 C71 P53786 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C72 name=collection1 org.apache.solr.core.SolrCore@4130bb6f url=http://127.0.0.1:19349/solr/collection1 node=127.0.0.1:19349_solr
[junit4:junit4]   2> 9992 T1186 C72 P19349 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 10004 T1160 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:30627/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@11dda6e8
[junit4:junit4]   2> 10005 T1210 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:30627
[junit4:junit4]   2> 10005 T1160 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10006 T1210 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:30627, initiating session
[junit4:junit4]   2> 10007 T1162 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32009
[junit4:junit4]   2> 10007 T1162 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32009
[junit4:junit4]   2> 10024 T1164 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a7c5fa7cf0005 with negotiated timeout 10000 for client /140.211.11.196:32009
[junit4:junit4]   2> 10024 T1210 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:30627, sessionid = 0x13a7c5fa7cf0005, negotiated timeout = 10000
[junit4:junit4]   2> 10025 T1211 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11dda6e8 name:ZooKeeperConnection Watcher:127.0.0.1:30627/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10025 T1160 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10026 T1160 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10028 T1160 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 10028 T1160 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 10045 T1160 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C73 name=collection1 org.apache.solr.core.SolrCore@57ad3b34 url=http://127.0.0.1:20503/solr/collection1 node=127.0.0.1:20503_solr
[junit4:junit4]   2> 10060 T1172 C73 P20503 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350706374600/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2ef451df; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10061 T1172 C73 P20503 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10065 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[1 (1416318297991806977)]} 0 10
[junit4:junit4]   2> 10066 T1212 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:30627/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3945b78e
[junit4:junit4]   2> 10066 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10001 (1416318297991806976)]} 0 11
[junit4:junit4]   2> 10067 T1214 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:30627
[junit4:junit4]   2> 10067 T1212 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10068 T1214 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:30627, initiating session
[junit4:junit4]   2> 10068 T1162 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:57266
[junit4:junit4]   2> 10069 T1162 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:57266
[junit4:junit4]   2> 10074 T1164 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a7c5fa7cf0006 with negotiated timeout 10000 for client /140.211.11.196:57266
[junit4:junit4]   2> 10074 T1214 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:30627, sessionid = 0x13a7c5fa7cf0006, negotiated timeout = 10000
[junit4:junit4]   2> 10074 T1215 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3945b78e name:ZooKeeperConnection Watcher:127.0.0.1:30627/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10074 T1212 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10075 T1212 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10084 T1189 C72 P19349 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@44293e3f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10085 T1189 C72 P19349 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10088 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10001 (1416318298018021376)]} 0 8
[junit4:junit4]   2> 10088 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[1 (1416318298019069952)]} 0 8
[junit4:junit4]   2> 10093 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[2 (1416318298030604288)]} 0 1
[junit4:junit4]   2> 10095 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10002 (1416318298031652864)]} 0 3
[junit4:junit4]   2> 10097 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[2 (1416318298034798592)]} 0 1
[junit4:junit4]   2> 10099 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10002 (1416318298036895744)]} 0 1
[junit4:junit4]   2> 10101 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[3 (1416318298038992896)]} 0 1
[junit4:junit4]   2> 10103 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10003 (1416318298041090048)]} 0 1
[junit4:junit4]   2> 10105 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[3 (1416318298043187200)]} 0 1
[junit4:junit4]   2> 10107 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10003 (1416318298045284352)]} 0 1
[junit4:junit4]   2> 10107 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[3 (-1416318298046332928)]} 0 0
[junit4:junit4]   2> 10111 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[3 (-1416318298049478656)]} 0 1
[junit4:junit4]   2> 10111 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10003 (-1416318298049478656)]} 0 1
[junit4:junit4]   2> 10113 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10003 (-1416318298052624384)]} 0 0
[junit4:junit4]   2> 10115 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[4 (1416318298052624384)]} 0 2
[junit4:junit4]   2> 10117 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10004 (1416318298055770112)]} 0 1
[junit4:junit4]   2> 10118 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[4 (1416318298056818688)]} 0 1
[junit4:junit4]   2> 10121 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10004 (1416318298059964416)]} 0 1
[junit4:junit4]   2> 10121 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[5 (1416318298059964416)]} 0 1
[junit4:junit4]   2> 10125 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[5 (1416318298064158720)]} 0 1
[junit4:junit4]   2> 10132 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10005 (1416318298064158720)]} 0 8
[junit4:junit4]   2> 10132 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[6 (1416318298068353024)]} 0 4
[junit4:junit4]   2> 10136 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[6 (1416318298075693056)]} 0 1
[junit4:junit4]   2> 10137 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10005 (1416318298075693057)]} 0 2
[junit4:junit4]   2> 10141 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10006 (1416318298079887360)]} 0 2
[junit4:junit4]   2> 10142 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[7 (1416318298079887361)]} 0 2
[junit4:junit4]   2> 10144 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10006 (1416318298084081664)]} 0 1
[junit4:junit4]   2> 10145 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[7 (1416318298085130240)]} 0 1
[junit4:junit4]   2> 10149 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[6 (-1416318298089324544)]} 0 1
[junit4:junit4]   2> 10149 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10007 (1416318298088275968)]} 0 2
[junit4:junit4]   2> 10151 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[6 (-1416318298092470273)]} 0 0
[junit4:junit4]   2> 10152 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10007 (1416318298092470272)]} 0 1
[junit4:junit4]   2> 10155 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10006 (-1416318298096664576)]} 0 0
[junit4:junit4]   2> 10156 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[8 (1416318298095616000)]} 0 2
[junit4:junit4]   2> 10159 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10006 (-1416318298099810304)]} 0 1
[junit4:junit4]   2> 10160 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[8 (1416318298100858880)]} 0 1
[junit4:junit4]   2> 10164 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[9 (1416318298105053184)]} 0 1
[junit4:junit4]   2> 10166 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10008 (1416318298105053185)]} 0 3
[junit4:junit4]   2> 10168 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[9 (1416318298109247488)]} 0 1
[junit4:junit4]   2> 10170 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10008 (1416318298111344640)]} 0 1
[junit4:junit4]   2> 10172 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10 (1416318298113441792)]} 0 1
[junit4:junit4]   2> 10174 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10009 (1416318298115538944)]} 0 1
[junit4:junit4]   2> 10178 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10009 (1416318298119733248)]} 0 1
[junit4:junit4]   2> 10182 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10 (1416318298117636096)]} 0 7
[junit4:junit4]   2> 10185 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[8 (-1416318298128121856)]} 0 0
[junit4:junit4]   2> 10186 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10010 (1416318298123927552)]} 0 5
[junit4:junit4]   2> 10189 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[8 (-1416318298132316160)]} 0 1
[junit4:junit4]   2> 10193 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[11 (1416318298134413312)]} 0 2
[junit4:junit4]   2> 10196 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10010 (1416318298132316161)]} 0 7
[junit4:junit4]   2> 10199 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[11 (1416318298139656192)]} 0 3
[junit4:junit4]   2> 10200 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10008 (-1416318298142801920)]} 0 1
[junit4:junit4]   2> 10203 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10008 (-1416318298146996224)]} 0 1
[junit4:junit4]   2> 10203 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[9 (-1416318298146996224)]} 0 1
[junit4:junit4]   2> 10206 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[9 (-1416318298150141952)]} 0 0
[junit4:junit4]   2> 10207 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10011 (1416318298149093376)]} 0 2
[junit4:junit4]   2> 10210 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10011 (1416318298153287680)]} 0 1
[junit4:junit4]   2> 10211 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[12 (1416318298153287680)]} 0 2
[junit4:junit4]   2> 10212 T1181 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10213 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10009 (-1416318298157481984)]} 0 0
[junit4:junit4]   2> 10214 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[12 (1416318298157481984)]} 0 1
[junit4:junit4]   2> 10216 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10009 (-1416318298160627712)]} 0 0
[junit4:junit4]   2> 10218 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[13 (1416318298161676288)]} 0 1
[junit4:junit4]   2> 10220 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10012 (1416318298163773440)]} 0 1
[junit4:junit4]   2> 10223 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[13 (1416318298165870592)]} 0 2
[junit4:junit4]   2> 10224 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10012 (1416318298167967744)]} 0 1
[junit4:junit4]   2> 10228 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[14 (1416318298171113472)]} 0 2
[junit4:junit4]   2> 10229 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10013 (1416318298173210624)]} 0 2
[junit4:junit4]   2> 10232 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10013 (1416318298176356352)]} 0 1
[junit4:junit4]   2> 10233 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[14 (1416318298176356353)]} 0 2
[junit4:junit4]   2> 10237 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10014 (1416318298181599232)]} 0 2
[junit4:junit4]   2> 10238 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[15 (1416318298181599233)]} 0 2
[junit4:junit4]   2> 10241 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10014 (1416318298184744960)]} 0 2
[junit4:junit4]   2> 10242 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[15 (1416318298186842112)]} 0 1
[junit4:junit4]   2> 10247 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[16 (1416318298191036416)]} 0 2
[junit4:junit4]   2> 10254 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10015 (1416318298188939264)]} 0 11
[junit4:junit4]   2> 10270 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[16 (1416318298216202240)]} 0 2
[junit4:junit4]   2> 10276 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10015 (1416318298221445120)]} 0 2
[junit4:junit4]   2> 10281 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[17 (1416318298226688000)]} 0 3
[junit4:junit4]   2> 10282 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10016 (1416318298228785152)]} 0 1
[junit4:junit4]   2> 10286 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[17 (1416318298232979456)]} 0 2
[junit4:junit4]   2> 10288 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10016 (1416318298234028032)]} 0 3
[junit4:junit4]   2> 10290 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[14 (-1416318298238222336)]} 0 1
[junit4:junit4]   2> 10293 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10017 (1416318298240319488)]} 0 2
[junit4:junit4]   2> 10294 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[14 (-1416318298242416640)]} 0 1
[junit4:junit4]   2> 10299 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[18 (1416318298245562368)]} 0 2
[junit4:junit4]   2> 10299 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10017 (1416318298245562368)]} 0 2
[junit4:junit4]   2> 10303 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10014 (-1416318298250805248)]} 0 1
[junit4:junit4]   2> 10304 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[18 (1416318298251853824)]} 0 2
[junit4:junit4]   2> 10306 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10014 (-1416318298253950976)]} 0 1
[junit4:junit4]   2> 10308 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[19 (1416318298256048128)]} 0 1
[junit4:junit4]   2> 10310 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10018 (1416318298258145280)]} 0 1
[junit4:junit4]   2> 10313 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[19 (1416318298260242432)]} 0 2
[junit4:junit4]   2> 10317 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[15 (-1416318298265485312)]} 0 1
[junit4:junit4]   2> 10320 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[15 (-1416318298268631040)]} 0 1
[junit4:junit4]   2> 10321 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10018 (1416318298261291008)]} 0 9
[junit4:junit4]   2> 10324 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[20 (1416318298272825344)]} 0 1
[junit4:junit4]   2> 10325 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10019 (1416318298272825345)]} 0 2
[junit4:junit4]   2> 10329 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10019 (1416318298277019648)]} 0 2
[junit4:junit4]   2> 10329 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[20 (1416318298275971072)]} 0 3
[junit4:junit4]   2> 10332 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10015 (-1416318298282262528)]} 0 0
[junit4:junit4]   2> 10332 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[16 (-1416318298282262529)]} 0 0
[junit4:junit4]   2> 10335 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10015 (-1416318298285408257)]} 0 0
[junit4:junit4]   2> 10335 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[16 (-1416318298285408256)]} 0 0
[junit4:junit4]   2> 10341 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10020 (1416318298288553985)]} 0 3
[junit4:junit4]   2> 10345 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[21 (1416318298288553984)]} 0 7
[junit4:junit4]   2> 10345 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10020 (1416318298294845440)]} 0 1
[junit4:junit4]   2> 10349 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10016 (-1416318298300088320)]} 0 1
[junit4:junit4]   2> 10352 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10016 (-1416318298303234048)]} 0 1
[junit4:junit4]   2> 10355 T1180 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10355 T1194 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10355 T1211 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10355 T1215 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10355 T1206 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10356 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[21 (1416318298299039744)]} 0 8
[junit4:junit4]   2> 10357 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10021 (1416318298306379776)]} 0 2
[junit4:junit4]   2> 10360 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[17 (-1416318298310574080)]} 0 1
[junit4:junit4]   2> 10374 T1198 C71 P53786 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1350706374600/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1dc706b8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10375 T1198 C71 P53786 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10376 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 10376 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[17 (-1416318298313719808)]} 0 14
[junit4:junit4]   2> 10378 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 10379 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10021 (1416318298311622656)]} 0 19
[junit4:junit4]   2> 10380 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[22 (1416318298331545600)]} 0 1
[junit4:junit4]   2> 10382 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10017 (-1416318298334691328)]} 0 0
[junit4:junit4]   2> 10387 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10388 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[22 (1416318298335739904)]} 0 5
[junit4:junit4]   2> 10388 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10389 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10017 (-1416318298337837056)]} 0 4
[junit4:junit4]   2> 10392 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[23 (1416318298344128512)]} 0 1
[junit4:junit4]   2> 10393 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10022 (1416318298345177088)]} 0 1
[junit4:junit4]   2> 10401 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10402 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[23 (1416318298348322816)]} 0 7
[junit4:junit4]   2>  C71_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:53786_solr, base_url=http://127.0.0.1:53786/solr}
[junit4:junit4]   2> 10402 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10403 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10022 (1416318298350419968)]} 0 7
[junit4:junit4]   2> 10406 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[24 (1416318298358808576)]} 0 1
[junit4:junit4]   2> 10408 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10023 (1416318298359857152)]} 0 2
[junit4:junit4]   2> 10414 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10415 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[24 (1416318298363002880)]} 0 6
[junit4:junit4]   2> 10415 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10416 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10023 (1416318298364051456)]} 0 6
[junit4:junit4]   2> 10419 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[25 (1416318298372440064)]} 0 1
[junit4:junit4]   2> 10420 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10024 (1416318298373488640)]} 0 1
[junit4:junit4]   2> 10427 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10428 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[25 (1416318298376634368)]} 0 5
[junit4:junit4]   2> 10428 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10429 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10024 (1416318298377682944)]} 0 6
[junit4:junit4]   2> 10432 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[18 (-1416318298387120128)]} 0 2
[junit4:junit4]   2> 10439 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10439 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10025 (1416318298387120129)]} 0 7
[junit4:junit4]   2> 10440 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[18 (-1416318298390265856)]} 0 5
[junit4:junit4]   2> 10446 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[26 (1416318298398654464)]} 0 3
[junit4:junit4]   2> 10448 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10448 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10025 (1416318298397605888)]} 0 6
[junit4:junit4]   2> 10451 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10018 (-1416318298407043072)]} 0 0
[junit4:junit4]   2> 10457 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10458 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10018 (-1416318298410188800)]} 0 4
[junit4:junit4]   2> 10460 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 10461 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[26 (1416318298404945920)]} 0 12
[junit4:junit4]   2> 10463 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10026 (1416318298417528832)]} 0 2
[junit4:junit4]   2> 10466 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[27 (1416318298421723136)]} 0 2
[junit4:junit4]   2> 10473 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10473 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10026 (1416318298422771712)]} 0 7
[junit4:junit4]   2> 10476 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10477 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[27 (1416318298426966016)]} 0 8
[junit4:junit4]   2> 10477 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10027 (1416318298433257472)]} 0 1
[junit4:junit4]   2> 10481 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[28 (1416318298437451776)]} 0 1
[junit4:junit4]   2> 10485 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10486 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10027 (1416318298438500352)]} 0 6
[junit4:junit4]   2> 10489 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10028 (1416318298445840384)]} 0 1
[junit4:junit4]   2> 10490 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10490 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[28 (1416318298441646080)]} 0 6
[junit4:junit4]   2> 10493 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[20 (-1416318298451083264)]} 0 0
[junit4:junit4]   2> 10497 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10498 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10028 (1416318298450034688)]} 0 6
[junit4:junit4]   2> 10498 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10499 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[20 (-1416318298454228992)]} 0 3
[junit4:junit4]   2> 10500 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10020 (-1416318298458423296)]} 0 0
[junit4:junit4]   2> 10503 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[29 (1416318298460520448)]} 0 1
[junit4:junit4]   2> 10505 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10506 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10020 (-1416318298461569024)]} 0 3
[junit4:junit4]   2> 10510 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10029 (1416318298467860480)]} 0 1
[junit4:junit4]   2> 10516 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10517 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[29 (1416318298464714752)]} 0 11
[junit4:junit4]   2> 10519 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10519 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10029 (1416318298472054784)]} 0 6
[junit4:junit4]   2> 10522 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[30 (1416318298479394816)]} 0 1
[junit4:junit4]   2> 10526 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10030 (1416318298481491968)]} 0 4
[junit4:junit4]   2> 10528 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10529 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[30 (1416318298483589120)]} 0 5
[junit4:junit4]   2> 10535 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[31 (1416318298491977728)]} 0 3
[junit4:junit4]   2> 10536 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10536 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10030 (1416318298489880576)]} 0 7
[junit4:junit4]   2> 10540 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10031 (1416318298499317760)]} 0 1
[junit4:junit4]   2> 10550 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10550 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10031 (1416318298503512064)]} 0 7
[junit4:junit4]   2> 10554 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 10555 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[31 (1416318298498269184)]} 0 17
[junit4:junit4]   2> 10555 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10032 (1416318298515046400)]} 0 2
[junit4:junit4]   2> 10558 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[32 (1416318298518192128)]} 0 1
[junit4:junit4]   2> 10564 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10565 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10032 (1416318298518192128)]} 0 8
[junit4:junit4]   2> 10566 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10567 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[32 (1416318298522386432)]} 0 6
[junit4:junit4]   2> 10569 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10022 (-1416318298530775040)]} 0 1
[junit4:junit4]   2> 10571 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[22 (-1416318298531823616)]} 0 1
[junit4:junit4]   2> 10575 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10575 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10022 (-1416318298532872192)]} 0 4
[junit4:junit4]   2> 10577 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10578 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[22 (-1416318298537066496)]} 0 4
[junit4:junit4]   2> 10580 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10033 (1416318298540212224)]} 0 2
[junit4:junit4]   2> 10582 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[33 (1416318298543357952)]} 0 1
[junit4:junit4]   2> 10588 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10590 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10033 (1416318298544406528)]} 0 8
[junit4:junit4]   2> 10592 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10592 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[33 (1416318298548600832)]} 0 6
[junit4:junit4]   2> 10594 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10034 (1416318298555940864)]} 0 1
[junit4:junit4]   2> 10596 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[34 (1416318298558038016)]} 0 1
[junit4:junit4]   2> 10602 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10603 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10034 (1416318298560135168)]} 0 6
[junit4:junit4]   2> 10604 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10604 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[34 (1416318298562232320)]} 0 5
[junit4:junit4]   2> 10606 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10023 (-1416318298569572352)]} 0 0
[junit4:junit4]   2> 10607 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[23 (-1416318298570620928)]} 0 0
[junit4:junit4]   2> 10611 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10612 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10023 (-1416318298572718080)]} 0 3
[junit4:junit4]   2> 10613 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10614 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[23 (-1416318298573766656)]} 0 4
[junit4:junit4]   2> 10616 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10035 (1416318298579009536)]} 0 1
[junit4:junit4]   2> 10624 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10625 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[35 (1416318298581106688)]} 0 8
[junit4:junit4]   2> 10625 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10035 (1416318298583203840)]} 0 6
[junit4:junit4]   2> 10628 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10024 (-1416318298591592448)]} 0 1
[junit4:junit4]   2> 10632 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10632 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10633 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[35 (1416318298592641024)]} 0 5
[junit4:junit4]   2> 10633 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10024 (-1416318298594738176)]} 0 3
[junit4:junit4]   2> 10636 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[24 (-1416318298601029632)]} 0 0
[junit4:junit4]   2> 10639 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10036 (1416318298601029633)]} 0 3
[junit4:junit4]   2> 10642 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10643 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[24 (-1416318298604175360)]} 0 4
[junit4:junit4]   2> 10646 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[36 (1416318298610466816)]} 0 1
[junit4:junit4]   2> 10649 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 10650 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10036 (1416318298606272512)]} 0 9
[junit4:junit4]   2> 10654 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10027 (-1416318298618855424)]} 0 1
[junit4:junit4]   2> 10655 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 10656 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[36 (1416318298613612544)]} 0 8
[junit4:junit4]   2> 10658 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10659 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10027 (-1416318298622001152)]} 0 3
[junit4:junit4]   2> 10659 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[27 (-1416318298625146880)]} 0 0
[junit4:junit4]   2> 10663 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10037 (1416318298627244032)]} 0 2
[junit4:junit4]   2> 10666 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10667 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[27 (-1416318298630389760)]} 0 4
[junit4:junit4]   2> 10670 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10671 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10037 (1416318298632486912)]} 0 5
[junit4:junit4]   2> 10672 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[37 (1416318298636681216)]} 0 2
[junit4:junit4]   2> 10674 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10038 (1416318298639826944)]} 0 1
[junit4:junit4]   2> 10679 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10680 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[37 (1416318298641924096)]} 0 6
[junit4:junit4]   2> 10682 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10682 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10038 (1416318298644021248)]} 0 5
[junit4:junit4]   2> 10683 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[38 (1416318298649264128)]} 0 1
[junit4:junit4]   2> 10686 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10039 (1416318298652409856)]} 0 1
[junit4:junit4]   2> 10693 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10694 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10039 (1416318298656604160)]} 0 5
[junit4:junit4]   2> 10696 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10697 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[38 (1416318298653458432)]} 0 11
[junit4:junit4]   2> 10698 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10040 (1416318298664992768)]} 0 1
[junit4:junit4]   2> 10701 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[39 (1416318298668138496)]} 0 1
[junit4:junit4]   2> 10707 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10708 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10040 (1416318298669187072)]} 0 7
[junit4:junit4]   2> 10708 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10709 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[39 (1416318298672332800)]} 0 5
[junit4:junit4]   2> 10712 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10033 (-1416318298679672833)]} 0 1
[junit4:junit4]   2> 10717 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10717 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10033 (-1416318298682818560)]} 0 3
[junit4:junit4]   2> 10723 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10041 (1416318298689110016)]} 0 3
[junit4:junit4]   2> 10730 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10730 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10041 (1416318298695401472)]} 0 4
[junit4:junit4]   2> 10732 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[40 (1416318298679672832)]} 0 21
[junit4:junit4]   2> 10733 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10034 (-1416318298702741504)]} 0 0
[junit4:junit4]   2> 10739 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10740 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10034 (-1416318298705887232)]} 0 4
[junit4:junit4]   2> 10745 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10042 (1416318298714275840)]} 0 2
[junit4:junit4]   2> 10756 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10757 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10042 (1416318298719518720)]} 0 8
[junit4:junit4]   2> 10759 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 10760 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[40 (1416318298704838656)]} 0 25
[junit4:junit4]   2> 10760 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10035 (-1416318298731053056)]} 0 1
[junit4:junit4]   2> 10764 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[33 (-1416318298735247360)]} 0 1
[junit4:junit4]   2> 10766 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10767 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10035 (-1416318298735247360)]} 0 4
[junit4:junit4]   2> 10769 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10770 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[33 (-1416318298738393088)]} 0 3
[junit4:junit4]   2> 10772 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10043 (1416318298742587392)]} 0 2
[junit4:junit4]   2> 10775 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[41 (1416318298745733120)]} 0 2
[junit4:junit4]   2> 10779 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10780 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10043 (1416318298747830272)]} 0 5
[junit4:junit4]   2> 10783 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10044 (1416318298754121728)]} 0 1
[junit4:junit4]   2> 10785 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10785 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[41 (1416318298750976000)]} 0 7
[junit4:junit4]   2> 10788 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[34 (-1416318298760413184)]} 0 0
[junit4:junit4]   2> 10790 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10790 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10044 (1416318298758316032)]} 0 4
[junit4:junit4]   2> 10794 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10794 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10045 (1416318298765656064)]} 0 1
[junit4:junit4]   2> 10794 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[34 (-1416318298763558912)]} 0 3
[junit4:junit4]   2> 10798 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[42 (1416318298769850368)]} 0 1
[junit4:junit4]   2> 10801 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10801 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10045 (1416318298769850368)]} 0 4
[junit4:junit4]   2> 10804 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10037 (-1416318298777190400)]} 0 0
[junit4:junit4]   2> 10805 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10806 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[42 (1416318298772996096)]} 0 6
[junit4:junit4]   2> 10808 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[35 (-1416318298781384704)]} 0 0
[junit4:junit4]   2> 10810 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10810 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10037 (-1416318298780336128)]} 0 3
[junit4:junit4]   2> 10812 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10813 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[35 (-1416318298783481856)]} 0 3
[junit4:junit4]   2> 10814 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10046 (1416318298786627584)]} 0 1
[junit4:junit4]   2> 10816 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[43 (1416318298788724736)]} 0 1
[junit4:junit4]   2> 10820 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10821 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10046 (1416318298789773312)]} 0 5
[junit4:junit4]   2> 10822 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10822 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[43 (1416318298791870464)]} 0 4
[junit4:junit4]   2> 10827 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[44 (1416318298799210496)]} 0 2
[junit4:junit4]   2> 10829 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10047 (1416318298797113344)]} 0 6
[junit4:junit4]   2> 10833 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10833 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[44 (1416318298803404800)]} 0 4
[junit4:junit4]   2> 10837 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[45 (1416318298810744832)]} 0 1
[junit4:junit4]   2> 10841 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 10841 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10047 (1416318298806550528)]} 0 9
[junit4:junit4]   2> 10844 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10038 (-1416318298819133440)]} 0 0
[junit4:junit4]   2> 10845 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10846 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[45 (1416318298814939136)]} 0 6
[junit4:junit4]   2> 10849 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10850 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[37 (-1416318298825424896)]} 0 1
[junit4:junit4]   2> 10850 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10038 (-1416318298822279168)]} 0 3
[junit4:junit4]   2> 10854 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10048 (1416318298828570624)]} 0 1
[junit4:junit4]   2> 10855 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10856 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[37 (-1416318298828570624)]} 0 3
[junit4:junit4]   2> 10859 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[46 (1416318298833813504)]} 0 1
[junit4:junit4]   2> 10860 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10860 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10048 (1416318298831716352)]} 0 4
[junit4:junit4]   2> 10864 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10049 (1416318298839056384)]} 0 1
[junit4:junit4]   2> 10865 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10865 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[46 (1416318298836959232)]} 0 4
[junit4:junit4]   2> 10869 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[47 (1416318298844299264)]} 0 1
[junit4:junit4]   2> 10870 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10870 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10049 (1416318298842202112)]} 0 4
[junit4:junit4]   2> 10873 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10041 (-1416318298849542144)]} 0 0
[junit4:junit4]   2> 10875 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10875 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[47 (1416318298847444992)]} 0 4
[junit4:junit4]   2> 10878 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[38 (-1416318298854785024)]} 0 0
[junit4:junit4]   2> 10878 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10879 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10041 (-1416318298852687872)]} 0 3
[junit4:junit4]   2> 10882 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10050 (1416318298857930752)]} 0 1
[junit4:junit4]   2> 10883 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10883 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[38 (-1416318298856882176)]} 0 3
[junit4:junit4]   2> 10887 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[48 (1416318298863173632)]} 0 1
[junit4:junit4]   2> 10891 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10891 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10050 (1416318298861076480)]} 0 7
[junit4:junit4]   2> 10893 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10893 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10043 (-1416318298870513664)]} 0 0
[junit4:junit4]   2> 10894 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[48 (1416318298866319360)]} 0 5
[junit4:junit4]   2> 10897 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[49 (1416318298873659392)]} 0 1
[junit4:junit4]   2> 10898 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10899 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10043 (-1416318298873659392)]} 0 3
[junit4:junit4]   2> 10904 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10905 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[49 (1416318298877853696)]} 0 5
[junit4:junit4]   2> 10906 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10051 (1416318298879950848)]} 0 4
[junit4:junit4]   2> 10907 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[41 (-1416318298885193728)]} 0 0
[junit4:junit4]   2> 10912 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10913 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[41 (-1416318298888339456)]} 0 3
[junit4:junit4]   2> 10916 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10917 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10051 (1416318298887290880)]} 0 8
[junit4:junit4]   2> 10918 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[50 (1416318298894630912)]} 0 2
[junit4:junit4]   2> 10920 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10052 (1416318298897776640)]} 0 1
[junit4:junit4]   2> 10928 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10929 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10052 (1416318298900922368)]} 0 7
[junit4:junit4]   2> 10931 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10046 (-1416318298910359552)]} 0 0
[junit4:junit4]   2> 10935 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 10936 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[50 (1416318298898825216)]} 0 16
[junit4:junit4]   2> 10936 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10937 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10046 (-1416318298913505280)]} 0 3
[junit4:junit4]   2> 10939 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[43 (-1416318298918748161)]} 0 0
[junit4:junit4]   2> 10940 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10053 (1416318298918748160)]} 0 1
[junit4:junit4]   2> 10944 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10944 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[43 (-1416318298921893888)]} 0 2
[junit4:junit4]   2> 10948 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10948 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[51 (1416318298928185344)]} 0 1
[junit4:junit4]   2> 10949 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10053 (1416318298921893889)]} 0 7
[junit4:junit4]   2> 10951 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10050 (-1416318298931331072)]} 0 0
[junit4:junit4]   2> 10956 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10957 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[51 (1416318298931331072)]} 0 6
[junit4:junit4]   2> 10957 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10958 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10050 (-1416318298934476800)]} 0 4
[junit4:junit4]   2> 10960 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[52 (1416318298939719680)]} 0 1
[junit4:junit4]   2> 10961 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10054 (1416318298940768256)]} 0 1
[junit4:junit4]   2> 10967 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10967 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[52 (1416318298942865408)]} 0 5
[junit4:junit4]   2> 10968 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10969 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10054 (1416318298943913984)]} 0 6
[junit4:junit4]   2> 10970 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[46 (-1416318298951254016)]} 0 0
[junit4:junit4]   2> 10971 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10053 (-1416318298952302592)]} 0 0
[junit4:junit4]   2> 10976 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10977 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10053 (-1416318298955448320)]} 0 4
[junit4:junit4]   2> 10977 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10977 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[46 (-1416318298954399744)]} 0 4
[junit4:junit4]   2> 10980 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10055 (1416318298960691200)]} 0 1
[junit4:junit4]   2> 10981 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[53 (1416318298961739776)]} 0 1
[junit4:junit4]   2> 10987 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10988 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10055 (1416318298963836928)]} 0 6
[junit4:junit4]   2> 10989 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10989 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[53 (1416318298965934080)]} 0 5
[junit4:junit4]   2> 10990 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10055 (-1416318298972225536)]} 0 0
[junit4:junit4]   2> 10992 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[50 (-1416318298974322688)]} 0 0
[junit4:junit4]   2> 10995 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10996 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10055 (-1416318298974322688)]} 0 4
[junit4:junit4]   2> 10997 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10997 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[50 (-1416318298976419840)]} 0 3
[junit4:junit4]   2> 10999 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10056 (1416318298980614144)]} 0 1
[junit4:junit4]   2> 11006 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[54 (1416318298982711296)]} 0 6
[junit4:junit4]   2> 11006 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11007 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10056 (1416318298983759872)]} 0 6
[junit4:junit4]   2> 11010 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10056 (-1416318298993197056)]} 0 0
[junit4:junit4]   2> 11013 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11014 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[54 (1416318298991099904)]} 0 6
[junit4:junit4]   2> 11015 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11016 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10056 (-1416318298996342784)]} 0 2
[junit4:junit4]   2> 11016 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[53 (-1416318298999488512)]} 0 0
[junit4:junit4]   2> 11020 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11020 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10057 (1416318299001585664)]} 0 2
[junit4:junit4]   2> 11020 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[53 (-1416318299001585664)]} 0 2
[junit4:junit4]   2> 11024 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[55 (1416318299006828544)]} 0 1
[junit4:junit4]   2> 11033 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 11033 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10057 (1416318299006828544)]} 0 10
[junit4:junit4]   2> 11033 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 11034 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[55 (1416318299009974272)]} 0 8
[junit4:junit4]   2> 11037 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[55 (-1416318299021508608)]} 0 0
[junit4:junit4]   2> 11037 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10058 (1416318299020460032)]} 0 1
[junit4:junit4]   2> 11042 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11043 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[55 (-1416318299024654336)]} 0 3
[junit4:junit4]   2> 11044 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11045 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10058 (1416318299024654337)]} 0 5
[junit4:junit4]   2> 11047 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[56 (1416318299030945792)]} 0 1
[junit4:junit4]   2> 11047 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10058 (-1416318299031994368)]} 0 0
[junit4:junit4]   2> 11052 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11053 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10058 (-1416318299035140096)]} 0 3
[junit4:junit4]   2> 11055 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11056 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10059 (1416318299040382976)]} 0 1
[junit4:junit4]   2> 11056 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[56 (1416318299035140097)]} 0 6
[junit4:junit4]   2> 11059 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[56 (-1416318299044577280)]} 0 0
[junit4:junit4]   2> 11064 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11064 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11065 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10059 (1416318299044577280)]} 0 6
[junit4:junit4]   2> 11065 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[56 (-1416318299047723008)]} 0 3
[junit4:junit4]   2> 11068 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10060 (1416318299052965888)]} 0 1
[junit4:junit4]   2> 11068 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[57 (1416318299052965889)]} 0 1
[junit4:junit4]   2> 11074 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11074 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10060 (1416318299056111616)]} 0 4
[junit4:junit4]   2> 11075 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11076 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[57 (1416318299056111617)]} 0 6
[junit4:junit4]   2> 11077 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10060 (-1416318299063451648)]} 0 0
[junit4:junit4]   2> 11080 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[58 (1416318299065548800)]} 0 1
[junit4:junit4]   2> 11083 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11084 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10060 (-1416318299066597376)]} 0 4
[junit4:junit4]   2> 11087 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10061 (1416318299072888832)]} 0 1
[junit4:junit4]   2> 11091 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11092 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[58 (1416318299068694528)]} 0 10
[junit4:junit4]   2> 11093 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11093 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10061 (1416318299076034560)]} 0 4
[junit4:junit4]   2> 11094 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[58 (-1416318299081277440)]} 0 0
[junit4:junit4]   2> 11098 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11098 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[58 (-1416318299083374592)]} 0 2
[junit4:junit4]   2> 11101 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10062 (1416318299083374592)]} 0 5
[junit4:junit4]   2> 11103 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[59 (1416318299088617472)]} 0 2
[junit4:junit4]   2> 11111 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11111 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[59 (1416318299093860352)]} 0 5
[junit4:junit4]   2> 11115 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[60 (1416318299102248960)]} 0 1
[junit4:junit4]   2> 11137 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=17 
[junit4:junit4]   2> 11138 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[60 (1416318299105394688)]} 0 21
[junit4:junit4]   2> 11140 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[60 (-1416318299129511936)]} 0 0
[junit4:junit4]   2> 11145 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11146 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[60 (-1416318299132657664)]} 0 3
[junit4:junit4]   2> 11153 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=43 
[junit4:junit4]   2> 11154 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10062 (1416318299090714624)]} 0 51
[junit4:junit4]   2> 11157 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[61 (1416318299137900544)]} 0 9
[junit4:junit4]   2> 11159 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10063 (1416318299147337728)]} 0 2
[junit4:junit4]   2> 11167 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11168 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11168 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[61 (1416318299151532032)]} 0 7
[junit4:junit4]   2> 11169 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10063 (1416318299152580608)]} 0 7
[junit4:junit4]   2> 11172 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10064 (1416318299162017792)]} 0 1
[junit4:junit4]   2> 11173 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[62 (1416318299162017793)]} 0 2
[junit4:junit4]   2> 11178 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11178 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10064 (1416318299165163520)]} 0 4
[junit4:junit4]   2> 11179 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11179 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[62 (1416318299166212096)]} 0 4
[junit4:junit4]   2> 11182 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10065 (1416318299172503552)]} 0 1
[junit4:junit4]   2> 11183 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[63 (1416318299173552128)]} 0 1
[junit4:junit4]   2> 11189 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11190 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11190 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10065 (1416318299176697857)]} 0 5
[junit4:junit4]   2> 11190 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[63 (1416318299176697856)]} 0 5
[junit4:junit4]   2> 11192 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10061 (-1416318299184037888)]} 0 0
[junit4:junit4]   2> 11194 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[64 (1416318299185086464)]} 0 1
[junit4:junit4]   2> 11197 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11198 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10061 (-1416318299187183616)]} 0 3
[junit4:junit4]   2> 11201 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11202 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[64 (1416318299189280768)]} 0 5
[junit4:junit4]   2> 11205 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10066 (1416318299192426496)]} 0 5
[junit4:junit4]   2> 11206 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[65 (1416318299196620800)]} 0 2
[junit4:junit4]   2> 11211 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11212 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10066 (1416318299199766528)]} 0 5
[junit4:junit4]   2> 11215 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10067 (1416318299207106560)]} 0 1
[junit4:junit4]   2> 11217 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 11217 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[65 (1416318299201863680)]} 0 8
[junit4:junit4]   2> 11220 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[61 (-1416318299213398016)]} 0 0
[junit4:junit4]   2> 11223 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11224 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10067 (1416318299211300864)]} 0 6
[junit4:junit4]   2> 11227 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10063 (-1416318299220738048)]} 0 1
[junit4:junit4]   2> 11227 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11228 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[61 (-1416318299218640896)]} 0 3
[junit4:junit4]   2> 11231 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[66 (1416318299223883776)]} 0 1
[junit4:junit4]   2> 11233 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11234 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10063 (-1416318299223883776)]} 0 4
[junit4:junit4]   2> 11238 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10068 (1416318299231223808)]} 0 1
[junit4:junit4]   2> 11238 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11239 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[66 (1416318299228078080)]} 0 5
[junit4:junit4]   2> 11242 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[67 (1416318299235418112)]} 0 1
[junit4:junit4]   2> 11245 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11246 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10068 (1416318299235418112)]} 0 5
[junit4:junit4]   2> 11248 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11248 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10066 (-1416318299242758144)]} 0 0
[junit4:junit4]   2> 11249 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[67 (1416318299238563840)]} 0 5
[junit4:junit4]   2> 11251 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[63 (-1416318299245903872)]} 0 0
[junit4:junit4]   2> 11253 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11254 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10066 (-1416318299245903872)]} 0 3
[junit4:junit4]   2> 11257 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10069 (1416318299251146752)]} 0 1
[junit4:junit4]   2> 11257 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11258 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[63 (-1416318299249049600)]} 0 4
[junit4:junit4]   2> 11261 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[68 (1416318299255341056)]} 0 1
[junit4:junit4]   2> 11263 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11263 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10069 (1416318299254292480)]} 0 4
[junit4:junit4]   2> 11266 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10067 (-1416318299261632512)]} 0 0
[junit4:junit4]   2> 11268 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11268 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[68 (1416318299258486784)]} 0 5
[junit4:junit4]   2> 11271 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11272 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[66 (-1416318299266875392)]} 0 1
[junit4:junit4]   2> 11272 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10067 (-1416318299264778240)]} 0 3
[junit4:junit4]   2> 11276 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10070 (1416318299271069696)]} 0 1
[junit4:junit4]   2> 11276 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11277 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[66 (-1416318299270021120)]} 0 3
[junit4:junit4]   2> 11280 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[69 (1416318299275264000)]} 0 1
[junit4:junit4]   2> 11282 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11284 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10070 (1416318299274215424)]} 0 6
[junit4:junit4]   2> 11290 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11290 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[69 (1416318299278409728)]} 0 8
[junit4:junit4]   2> 11291 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10071 (1416318299282604032)]} 0 5
[junit4:junit4]   2> 11294 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[67 (-1416318299289944064)]} 0 1
[junit4:junit4]   2> 11299 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11299 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10071 (1416318299290992640)]} 0 5
[junit4:junit4]   2> 11301 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11302 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[67 (-1416318299295186944)]} 0 4
[junit4:junit4]   2> 11303 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10068 (-1416318299299381248)]} 0 1
[junit4:junit4]   2> 11306 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[70 (1416318299301478400)]} 0 2
[junit4:junit4]   2> 11308 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11309 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10068 (-1416318299303575552)]} 0 3
[junit4:junit4]   2> 11312 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10072 (1416318299308818432)]} 0 1
[junit4:junit4]   2> 11316 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 11316 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[70 (1416318299305672704)]} 0 8
[junit4:junit4]   2> 11320 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[71 (1416318299317207040)]} 0 1
[junit4:junit4]   2> 11325 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11325 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10072 (1416318299313012736)]} 0 10
[junit4:junit4]   2> 11326 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11327 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[71 (1416318299320352768)]} 0 5
[junit4:junit4]   2> 11328 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10072 (-1416318299326644224)]} 0 0
[junit4:junit4]   2> 11331 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[68 (-1416318299328741376)]} 0 1
[junit4:junit4]   2> 11333 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11334 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10072 (-1416318299329789952)]} 0 3
[junit4:junit4]   2> 11335 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11336 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[68 (-1416318299331887104)]} 0 3
[junit4:junit4]   2> 11337 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10073 (1416318299335032832)]} 0 1
[junit4:junit4]   2> 11339 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[72 (1416318299337129984)]} 0 1
[junit4:junit4]   2> 11346 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11346 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11346 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10073 (1416318299339227136)]} 0 6
[junit4:junit4]   2> 11347 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[72 (1416318299341324288)]} 0 5
[junit4:junit4]   2> 11349 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[72 (-1416318299348664320)]} 0 0
[junit4:junit4]   2> 11349 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10073 (-1416318299348664321)]} 0 0
[junit4:junit4]   2> 11354 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11354 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11355 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[72 (-1416318299351810048)]} 0 3
[junit4:junit4]   2> 11355 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10073 (-1416318299351810049)]} 0 3
[junit4:junit4]   2> 11358 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[73 (1416318299357052928)]} 0 1
[junit4:junit4]   2> 11359 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10074 (1416318299358101504)]} 0 1
[junit4:junit4]   2> 11365 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11366 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11366 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[73 (1416318299361247232)]} 0 5
[junit4:junit4]   2> 11366 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10074 (1416318299362295808)]} 0 4
[junit4:junit4]   2> 11369 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[73 (-1416318299369635841)]} 0 0
[junit4:junit4]   2> 11370 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10075 (1416318299369635840)]} 0 1
[junit4:junit4]   2> 11374 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11375 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[73 (-1416318299372781568)]} 0 3
[junit4:junit4]   2> 11377 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11378 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[74 (1416318299378024448)]} 0 1
[junit4:junit4]   2> 11378 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10075 (1416318299373830144)]} 0 5
[junit4:junit4]   2> 11380 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10074 (-1416318299381170176)]} 0 0
[junit4:junit4]   2> 11384 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11385 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[74 (1416318299381170176)]} 0 5
[junit4:junit4]   2> 11385 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11386 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10074 (-1416318299384315904)]} 0 3
[junit4:junit4]   2> 11391 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10076 (1416318299389558784)]} 0 3
[junit4:junit4]   2> 11397 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11398 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10076 (1416318299394801664)]} 0 5
[junit4:junit4]   2> 11401 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10075 (-1416318299402141696)]} 0 1
[junit4:junit4]   2> 11406 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11407 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10075 (-1416318299405287424)]} 0 4
[junit4:junit4]   2> 11410 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[75 (1416318299388510208)]} 0 23
[junit4:junit4]   2> 11411 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10077 (1416318299412627456)]} 0 1
[junit4:junit4]   2> 11419 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 11420 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[75 (1416318299415773185)]} 0 7
[junit4:junit4]   2> 11421 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 11421 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10077 (1416318299415773184)]} 0 8
[junit4:junit4]   2> 11423 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[74 (-1416318299425210368)]} 0 1
[junit4:junit4]   2> 11426 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10078 (1416318299427307520)]} 0 2
[junit4:junit4]   2> 11429 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11430 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[74 (-1416318299430453248)]} 0 4
[junit4:junit4]   2> 11434 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[76 (1416318299436744704)]} 0 1
[junit4:junit4]   2> 11435 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11436 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10078 (1416318299432550400)]} 0 7
[junit4:junit4]   2> 11441 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10079 (1416318299443036160)]} 0 2
[junit4:junit4]   2> 11444 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11444 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[76 (1416318299443036160)]} 0 6
[junit4:junit4]   2> 11447 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[75 (-1416318299451424768)]} 0 0
[junit4:junit4]   2> 11448 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11449 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10079 (1416318299448279040)]} 0 5
[junit4:junit4]   2> 11451 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10076 (-1416318299455619072)]} 0 0
[junit4:junit4]   2> 11453 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11454 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[75 (-1416318299454570496)]} 0 4
[junit4:junit4]   2> 11457 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11458 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10076 (-1416318299458764800)]} 0 4
[junit4:junit4]   2> 11458 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[77 (1416318299461910528)]} 0 1
[junit4:junit4]   2> 11462 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10080 (1416318299466104832)]} 0 1
[junit4:junit4]   2> 11466 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11467 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[77 (1416318299466104832)]} 0 5
[junit4:junit4]   2> 11469 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11470 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10080 (1416318299470299136)]} 0 5
[junit4:junit4]   2> 11470 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[78 (1416318299474493440)]} 0 1
[junit4:junit4]   2> 11472 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10079 (-1416318299477639168)]} 0 0
[junit4:junit4]   2> 11478 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11479 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10079 (-1416318299480784896)]} 0 4
[junit4:junit4]   2> 11483 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10081 (1416318299488124928)]} 0 1
[junit4:junit4]   2> 11491 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11492 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10081 (1416318299492319232)]} 0 6
[junit4:junit4]   2> 11496 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10080 (-1416318299501756416)]} 0 1
[junit4:junit4]   2> 11501 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11502 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10080 (-1416318299504902144)]} 0 4
[junit4:junit4]   2> 11509 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11510 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[78 (1416318299478687744)]} 0 37
[junit4:junit4]   2> 11519 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[79 (1416318299520630784)]} 0 6
[junit4:junit4]   2> 11521 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10082 (1416318299513290752)]} 0 15
[junit4:junit4]   2> 11528 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11530 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[79 (1416318299529019392)]} 0 9
[junit4:junit4]   2> 11534 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[76 (-1416318299541602304)]} 0 1
[junit4:junit4]   2> 11540 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11541 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[76 (-1416318299545796608)]} 0 4
[junit4:junit4]   2> 11546 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[80 (1416318299553136640)]} 0 2
[junit4:junit4]   2> 11560 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 11561 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[80 (1416318299558379520)]} 0 12
[junit4:junit4]   2> 11563 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11564 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10082 (1416318299533213696)]} 0 40
[junit4:junit4]   2> 11564 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[79 (-1416318299574108160)]} 0 0
[junit4:junit4]   2> 11567 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10082 (-1416318299577253888)]} 0 0
[junit4:junit4]   2> 11571 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11571 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[79 (-1416318299577253888)]} 0 4
[junit4:junit4]   2> 11574 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11574 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10082 (-1416318299581448192)]} 0 4
[junit4:junit4]   2> 11575 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[81 (1416318299584593920)]} 0 1
[junit4:junit4]   2> 11578 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10083 (1416318299587739648)]} 0 1
[junit4:junit4]   2> 11583 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11584 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[81 (1416318299588788224)]} 0 6
[junit4:junit4]   2> 11587 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[80 (-1416318299598225408)]} 0 0
[junit4:junit4]   2> 11588 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11589 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10083 (1416318299591933952)]} 0 8
[junit4:junit4]   2> 11592 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10084 (1416318299602419712)]} 0 1
[junit4:junit4]   2> 11594 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11594 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[80 (-1416318299601371136)]} 0 4
[junit4:junit4]   2> 11598 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[82 (1416318299608711168)]} 0 1
[junit4:junit4]   2> 11600 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11600 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10084 (1416318299606614016)]} 0 5
[junit4:junit4]   2> 11605 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10085 (1416318299615002624)]} 0 2
[junit4:junit4]   2> 11608 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11609 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[82 (1416318299615002624)]} 0 7
[junit4:junit4]   2> 11613 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[82 (-1416318299624439808)]} 0 1
[junit4:junit4]   2> 11614 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11615 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10085 (1416318299620245504)]} 0 7
[junit4:junit4]   2> 11619 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10086 (1416318299630731264)]} 0 1
[junit4:junit4]   2> 11620 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11620 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[82 (-1416318299628634112)]} 0 4
[junit4:junit4]   2> 11625 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[83 (1416318299635974144)]} 0 2
[junit4:junit4]   2> 11628 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11629 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10086 (1416318299634925568)]} 0 7
[junit4:junit4]   2> 11632 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10084 (-1416318299645411328)]} 0 0
[junit4:junit4]   2> 11633 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11633 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[83 (1416318299641217024)]} 0 5
[junit4:junit4]   2> 11638 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11639 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10084 (-1416318299648557056)]} 0 4
[junit4:junit4]   2> 11643 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[84 (1416318299649605632)]} 0 7
[junit4:junit4]   2> 11644 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10087 (1416318299655897088)]} 0 2
[junit4:junit4]   2> 11653 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 11654 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[84 (1416318299660091392)]} 0 8
[junit4:junit4]   2> 11657 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 11658 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10087 (1416318299661139968)]} 0 11
[junit4:junit4]   2> 11658 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[85 (1416318299671625728)]} 0 1
[junit4:junit4]   2> 11662 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10088 (1416318299675820032)]} 0 1
[junit4:junit4]   2> 11666 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11667 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[85 (1416318299675820032)]} 0 6
[junit4:junit4]   2> 11670 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11671 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[86 (1416318299684208640)]} 0 2
[junit4:junit4]   2> 11671 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10088 (1416318299680014336)]} 0 6
[junit4:junit4]   2> 11675 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10085 (-1416318299689451520)]} 0 0
[junit4:junit4]   2> 11679 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11680 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[86 (1416318299689451520)]} 0 6
[junit4:junit4]   2> 11680 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11681 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10085 (-1416318299693645824)]} 0 3
[junit4:junit4]   2> 11682 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[84 (-1416318299697840128)]} 0 0
[junit4:junit4]   2> 11684 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10089 (1416318299698888704)]} 0 1
[junit4:junit4]   2> 11687 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11688 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[84 (-1416318299700985856)]} 0 3
[junit4:junit4]   2> 11691 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11691 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[87 (1416318299706228736)]} 0 1
[junit4:junit4]   2> 11691 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10089 (1416318299703083008)]} 0 4
[junit4:junit4]   2> 11694 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10087 (-1416318299710423040)]} 0 0
[junit4:junit4]   2> 11699 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11700 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10087 (-1416318299713568768)]} 0 3
[junit4:junit4]   2> 11703 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11703 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[87 (1416318299710423040)]} 0 9
[junit4:junit4]   2> 11704 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10090 (1416318299719860224)]} 0 1
[junit4:junit4]   2> 11707 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[88 (1416318299723005952)]} 0 1
[junit4:junit4]   2> 11714 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11714 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11715 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10090 (1416318299724054528)]} 0 8
[junit4:junit4]   2> 11715 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[88 (1416318299727200256)]} 0 5
[junit4:junit4]   2> 11717 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[85 (-1416318299734540288)]} 0 0
[junit4:junit4]   2> 11719 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10091 (1416318299734540289)]} 0 2
[junit4:junit4]   2> 11722 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11723 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[85 (-1416318299737686016)]} 0 3
[junit4:junit4]   2> 11725 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11726 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10091 (1416318299738734592)]} 0 5
[junit4:junit4]   2> 11729 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10090 (-1416318299747123200)]} 0 0
[junit4:junit4]   2> 11733 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[89 (1416318299742928896)]} 0 8
[junit4:junit4]   2> 11736 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11736 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10090 (-1416318299750268928)]} 0 4
[junit4:junit4]   2> 11741 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10092 (1416318299757608960)]} 0 2
[junit4:junit4]   2> 11742 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11743 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[89 (1416318299754463232)]} 0 7
[junit4:junit4]   2> 11745 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[87 (-1416318299763900416)]} 0 0
[junit4:junit4]   2> 11752 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11752 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[87 (-1416318299767046144)]} 0 4
[junit4:junit4]   2> 11756 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[90 (1416318299774386176)]} 0 1
[junit4:junit4]   2> 11761 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=14 
[junit4:junit4]   2> 11762 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10092 (1416318299762851840)]} 0 18
[junit4:junit4]   2> 11764 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11765 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[90 (1416318299778580480)]} 0 6
[junit4:junit4]   2> 11766 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10091 (-1416318299784871936)]} 0 1
[junit4:junit4]   2> 11770 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[91 (1416318299789066240)]} 0 2
[junit4:junit4]   2> 11771 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11772 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10091 (-1416318299789066240)]} 0 4
[junit4:junit4]   2> 11777 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10093 (1416318299796406272)]} 0 2
[junit4:junit4]   2> 11779 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11780 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[91 (1416318299793260544)]} 0 6
[junit4:junit4]   2> 11782 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[90 (-1416318299802697728)]} 0 0
[junit4:junit4]   2> 11788 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11788 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11789 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[90 (-1416318299805843456)]} 0 4
[junit4:junit4]   2> 11789 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10093 (1416318299800600576)]} 0 9
[junit4:junit4]   2> 11793 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10093 (-1416318299813183489)]} 0 1
[junit4:junit4]   2> 11793 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[92 (1416318299813183488)]} 0 1
[junit4:junit4]   2> 11797 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11798 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[10093 (-1416318299816329216)]} 0 3
[junit4:junit4]   2> 11800 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11801 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[92 (1416318299817377792)]} 0 5
[junit4:junit4]   2> 11803 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10094 (1416318299822620672)]} 0 2
[junit4:junit4]   2> 11803 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[91 (-1416318299824717824)]} 0 0
[junit4:junit4]   2> 11810 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11810 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[91 (-1416318299827863553)]} 0 4
[junit4:junit4]   2> 11811 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11812 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10094 (1416318299827863552)]} 0 6
[junit4:junit4]   2> 11814 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[93 (1416318299835203584)]} 0 1
[junit4:junit4]   2> 11815 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10095 (1416318299836252160)]} 0 1
[junit4:junit4]   2> 11822 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11823 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[93 (1416318299839397888)]} 0 6
[junit4:junit4]   2> 11824 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11825 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10095 (1416318299840446464)]} 0 7
[junit4:junit4]   2> 11826 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[93 (-1416318299848835072)]} 0 0
[junit4:junit4]   2> 11828 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[10096 (1416318299849883648)]} 0 1
[junit4:junit4]   2> 11831 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11832 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[93 (-1416318299851980800)]} 0 3
[junit4:junit4]   2> 11835 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11836 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[10096 (1416318299854077952)]} 0 5
[junit4:junit4]   2> 11838 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[10094 (-1416318299861417984)]} 0 0
[junit4:junit4]   2> 11844 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11845 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10094 (-1416318299864563712)]} 0 4
[junit4:junit4]   2> 11848 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[94 (1416318299858272256)]} 0 13
[junit4:junit4]   2> 11851 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[10097 (1416318299871903744)]} 0 3
[junit4:junit4]   2> 11855 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11856 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[94 (1416318299875049472)]} 0 5
[junit4:junit4]   2> 11860 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[95 (1416318299883438080)]} 0 1
[junit4:junit4]   2> 11880 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11881 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[95 (1416318299887632384)]} 0 18
[junit4:junit4]   2> 11885 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[96 (1416318299909652480)]} 0 1
[junit4:junit4]   2> 11894 T1199 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11895 T1189 C72 P19349 /update {wt=javabin&version=2} {add=[96 (1416318299913846784)]} 0 7
[junit4:junit4]   2> 11898 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=41 
[junit4:junit4]   2> 11899 T1188 C72 P19349 /update {wt=javabin&version=2} {add=[10097 (1416318299878195200)]} 0 45
[junit4:junit4]   2> 11899 T1173 C73 P20503 /update {wt=javabin&version=2} {delete=[94 (-1416318299924332544)]} 0 1
[junit4:junit4]   2> 11902 T1172 C73 P20503 /update {wt=javabin&version=2} {delete=[10096 (-1416318299927478272)]} 0 1
[junit4:junit4]   2> 11905 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11906 T1188 C72 P19349 /update {wt=javabin&version=2} {delete=[94 (-1416318299927478272)]} 0 5
[junit4:junit4]   2> 11909 T1198 C71 P53786 REQ /update {distrib.from=http://127.0.0.1:19349/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11909 T1189 C72 P19349 /update {wt=javabin&version=2} {delete=[10096 (-1416318299931672576)]} 0 4
[junit4:junit4]   2> 11909 T1172 C73 P20503 /update {wt=javabin&version=2} {add=[97 (1416318299934818304)]} 0 1
[junit4:junit4]   2> 11913 T1173 C73 P20503 /update {wt=javabin&version=2} {add=[1009

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

nt has reached 0 - closing IndexWriter
[junit4:junit4]   2> 34242 T1160 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 34244 T1160 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 34346 T1194 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 34346 T1160 oaz.ZooKeeper.close Session: 0x13a7c5fa7cf0003 closed
[junit4:junit4]   2> 34348 T1160 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 34400 T1160 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 53786
[junit4:junit4]   2> 34400 T1160 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1445857099
[junit4:junit4]   2> 34502 T1261 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 34502 T1160 oaz.ZooKeeper.close Session: 0x13a7c5fa7cf0007 closed
[junit4:junit4]   2> 34504 T1160 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 34733 T1160 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=5E7B387030263A41 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en_GB -Dtests.timezone=America/Argentina/Cordoba -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   34.8s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:53786/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([5E7B387030263A41:DF9DB66847795A7D]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 34760 T1160 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=en_GB, timezone=America/Argentina/Cordoba
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7.0_06 (64-bit)/cpus=16,threads=1,free=51165160,total=198180864
[junit4:junit4]   2> NOTE: All tests run in this JVM: [BasicFunctionalityTest, TestUpdate, TestCodecSupport, TestReplicationHandler, FileUtilsTest, TestPropInjectDefaults, FullSolrCloudDistribCmdsTest, TestPluginEnable, TestSort, SoftAutoCommitTest, TestDistributedSearch, TestTrie, TestUtils, LegacyHTMLStripCharFilterTest, QueryParsingTest, DirectUpdateHandlerTest, TestDFRSimilarityFactory, TestCSVLoader, AnalysisAfterCoreReloadTest, TestPhraseSuggestions, TestBinaryField, ZkNodePropsTest, TestSurroundQueryParser, IndexSchemaTest, DocumentAnalysisRequestHandlerTest, TestPropInject, FieldMutatingUpdateProcessorTest, SuggesterTSTTest, TestElisionMultitermQuery, PingRequestHandlerTest, ReturnFieldsTest, TestArbitraryIndexDir, PrimUtilsTest, ShowFileRequestHandlerTest, TestLRUCache, OverseerTest, TestValueSourceCache, TestQuerySenderListener, QueryEqualityTest, SolrInfoMBeanTest, BasicZkTest, ConvertedLegacyTest, DocumentBuilderTest, LukeRequestHandlerTest, TermVectorComponentTest, DirectSolrSpellCheckerTest, UpdateParamsTest, TestSolrCoreProperties, ZkSolrClientTest, PrimitiveFieldTypeTest, RequiredFieldsTest, HighlighterTest, TestSolr4Spatial, SuggesterTest, SpellingQueryConverterTest, TestOmitPositions, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 34.79s, 1 test, 1 error <<< FAILURES!

[...truncated 447 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:335: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build.xml:178: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/module-build.xml:61: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:1170: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:838: There were test failures: 231 suites, 957 tests, 1 error, 21 ignored (3 assumptions)

Total time: 37 minutes 56 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message