lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b58) - Build # 2077 - Failure!
Date Tue, 30 Oct 2012 04:39:36 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/2077/
Java: 32bit/jdk1.8.0-ea-b58 -client -XX:+UseG1GC

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

Error Message:
Server at http://127.0.0.1:55384/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:55384/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([EB57BF482F92D353:6AB1315058CDB36F]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:374)
	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:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 8451 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 2 T337 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1351571026505
[junit4:junit4]   2> 3 T337 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T338 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 5 T338 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 5 T338 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 104 T337 oasc.ZkTestServer.run start zk server on port:35354
[junit4:junit4]   2> 104 T337 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:35354 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ec187d
[junit4:junit4]   2> 105 T343 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:35354
[junit4:junit4]   2> 105 T337 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T343 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:35354, initiating session
[junit4:junit4]   2> 106 T339 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:35749
[junit4:junit4]   2> 106 T339 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:35749
[junit4:junit4]   2> 106 T341 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 112 T341 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aafe9324c0000 with negotiated timeout 10000 for client /127.0.0.1:35749
[junit4:junit4]   2> 112 T343 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:35354, sessionid = 0x13aafe9324c0000, negotiated timeout = 10000
[junit4:junit4]   2> 112 T344 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ec187d name:ZooKeeperConnection Watcher:127.0.0.1:35354 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 112 T337 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 113 T337 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 115 T342 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aafe9324c0000
[junit4:junit4]   2> 115 T344 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 116 T339 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:35749 which had sessionid 0x13aafe9324c0000
[junit4:junit4]   2> 115 T337 oaz.ZooKeeper.close Session: 0x13aafe9324c0000 closed
[junit4:junit4]   2> 116 T337 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:35354/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@19ffee6
[junit4:junit4]   2> 117 T345 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:35354
[junit4:junit4]   2> 117 T337 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 118 T339 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:35750
[junit4:junit4]   2> 118 T345 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:35354, initiating session
[junit4:junit4]   2> 118 T339 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:35750
[junit4:junit4]   2> 119 T341 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aafe9324c0001 with negotiated timeout 10000 for client /127.0.0.1:35750
[junit4:junit4]   2> 119 T345 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:35354, sessionid = 0x13aafe9324c0001, negotiated timeout = 10000
[junit4:junit4]   2> 120 T346 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19ffee6 name:ZooKeeperConnection Watcher:127.0.0.1:35354/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 120 T337 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 120 T337 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 123 T337 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 124 T337 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 125 T337 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 127 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 127 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 130 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 131 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 133 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 134 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 135 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 136 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 137 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 138 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 139 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 140 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 141 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 142 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 143 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 144 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 145 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 146 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 147 T337 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 148 T337 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 149 T342 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aafe9324c0001
[junit4:junit4]   2> 150 T339 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:35750 which had sessionid 0x13aafe9324c0001
[junit4:junit4]   2> 150 T337 oaz.ZooKeeper.close Session: 0x13aafe9324c0001 closed
[junit4:junit4]   2> 150 T346 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 151 T337 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 154 T337 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:46411
[junit4:junit4]   2> 155 T337 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 155 T337 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 156 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 171 T337 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 171 T337 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 172 T337 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 172 T337 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 172 T337 oasc.CoreContainer.<init> New CoreContainer 13829686
[junit4:junit4]   2> 173 T337 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 173 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 194 T337 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 204 T337 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:35354/solr
[junit4:junit4]   2> 204 T337 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:35354/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1826dbd
[junit4:junit4]   2> 205 T356 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:35354
[junit4:junit4]   2> 205 T337 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 206 T356 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:35354, initiating session
[junit4:junit4]   2> 206 T339 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:35751
[junit4:junit4]   2> 207 T339 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:35751
[junit4:junit4]   2> 208 T341 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aafe9324c0002 with negotiated timeout 5000 for client /127.0.0.1:35751
[junit4:junit4]   2> 208 T356 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:35354, sessionid = 0x13aafe9324c0002, negotiated timeout = 5000
[junit4:junit4]   2> 208 T357 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1826dbd name:ZooKeeperConnection Watcher:127.0.0.1:35354/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 208 T337 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 209 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 210 T337 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 211 T337 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46411_solr
[junit4:junit4]   2> 211 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:46411_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:46411_solr
[junit4:junit4]   2> 212 T337 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46411_solr
[junit4:junit4]   2> 213 T337 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 214 T337 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 214 T337 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 214 T337 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 215 T337 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 215 T337 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 215 T337 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 216 T337 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 216 T337 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 216 T337 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 220 T337 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 223 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 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> 223 T337 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 225 T337 oasc.Overseer.start Overseer (id=88576558793228290-127.0.0.1:46411_solr-n_0000000000) starting
[junit4:junit4]   2> 225 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 226 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 226 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 227 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 228 T359 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 228 T337 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 230 T337 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 231 T358 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 234 T337 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 235 T337 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 235 T337 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 236 T337 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 237 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 237 T337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 238 T337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 271 T337 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 320 T337 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 322 T337 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 326 T337 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 755 T337 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 762 T337 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 765 T337 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 774 T337 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 778 T337 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 785 T337 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 786 T337 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 788 T337 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351571026505/control/data/
[junit4:junit4]   2> 788 T337 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f2eca1
[junit4:junit4]   2> 795 T337 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351571026505/control/data/index/
[junit4:junit4]   2> 795 T337 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351571026505/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 796 T337 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351571026505/control/data/index forceNew:false
[junit4:junit4]   2> 800 T337 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1351571026505/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 800 T337 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 801 T337 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 803 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 803 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 803 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 804 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 805 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 805 T337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 805 T337 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 806 T337 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 806 T337 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 807 T337 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 807 T337 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 808 T337 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 808 T337 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 809 T337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 809 T337 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 810 T337 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 810 T337 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 811 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 811 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 812 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 812 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 812 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 813 T337 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 813 T337 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 814 T337 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 817 T337 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 819 T337 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 820 T337 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SYNONYMS.TXT, CURRENCY.XML, OLD_SYNONYMS.TXT, SCHEMA.XML, OPEN-EXCHANGE-RATES.JSON, MAPPING-ISOLATIN1ACCENT.TXT, STOPWORDS.TXT, PROTWORDS.TXT]
[junit4:junit4]   2> 822 T337 oass.SolrIndexSearcher.<init> Opening Searcher@117eb10 main
[junit4:junit4]   2> 823 T337 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 823 T337 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 824 T337 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 831 T337 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 854 T360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@117eb10 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 860 T337 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 861 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 862 T337 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 863 T337 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:46411/solr shardId:control_shard
[junit4:junit4]   2> 864 T337 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 868 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:delete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 869 T337 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 870 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1236 T358 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1237 T358 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46411_solr",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46411/solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 1238 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1241 T357 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1371 T337 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1372 T337 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1373 T337 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:46411/solr/collection1/
[junit4:junit4]   2> 1374 T337 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1375 T337 oasc.SyncStrategy.syncToMe http://127.0.0.1:46411/solr/collection1/ has no replicas
[junit4:junit4]   2> 1375 T337 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:46411/solr/collection1/
[junit4:junit4]   2> 1376 T337 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1381 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1745 T358 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1750 T357 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1784 T337 oasc.ZkController.register We are http://127.0.0.1:46411/solr/collection1/ and leader is http://127.0.0.1:46411/solr/collection1/
[junit4:junit4]   2> 1785 T337 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:46411/solr
[junit4:junit4]   2> 1786 T337 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1786 T337 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1789 T337 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1791 T337 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1791 T337 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1792 T337 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1862 T337 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1865 T337 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:43471
[junit4:junit4]   2> 1866 T337 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1866 T337 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300
[junit4:junit4]   2> 1866 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300/'
[junit4:junit4]   2> 1880 T337 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1880 T337 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1880 T337 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300
[junit4:junit4]   2> 1881 T337 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300/solr.xml
[junit4:junit4]   2> 1881 T337 oasc.CoreContainer.<init> New CoreContainer 18450501
[junit4:junit4]   2> 1881 T337 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300/'
[junit4:junit4]   2> 1882 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300/'
[junit4:junit4]   2> 1895 T337 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1905 T337 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:35354/solr
[junit4:junit4]   2> 1906 T337 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:35354/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ab65bd
[junit4:junit4]   2> 1906 T370 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:35354
[junit4:junit4]   2> 1906 T337 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1908 T370 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:35354, initiating session
[junit4:junit4]   2> 1908 T339 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:35753
[junit4:junit4]   2> 1908 T339 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:35753
[junit4:junit4]   2> 1910 T341 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aafe9324c0003 with negotiated timeout 5000 for client /127.0.0.1:35753
[junit4:junit4]   2> 1910 T370 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:35354, sessionid = 0x13aafe9324c0003, negotiated timeout = 5000
[junit4:junit4]   2> 1911 T371 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ab65bd name:ZooKeeperConnection Watcher:127.0.0.1:35354/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1911 T337 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1912 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1914 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1914 T337 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43471_solr
[junit4:junit4]   2> 1915 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:43471_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:43471_solr
[junit4:junit4]   2> 1916 T337 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43471_solr
[junit4:junit4]   2> 1918 T357 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 1919 T337 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1919 T337 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1920 T337 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1920 T337 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1921 T337 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1921 T337 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1921 T337 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1922 T337 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1922 T337 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1923 T337 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1929 T337 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1934 T337 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300/collection1
[junit4:junit4]   2> 1934 T337 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 1935 T337 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1936 T337 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 1937 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300/collection1/'
[junit4:junit4]   2> 1938 T337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty1-1351571028300/collection1/lib/README' to classloader
[junit4:junit4]   2> 1939 T337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty1-1351571028300/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1984 T337 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 2046 T337 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2047 T337 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2052 T337 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2253 T358 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2254 T358 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46411_solr",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46411/solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"active"}
[junit4:junit4]   2> 2256 T357 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2256 T371 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2468 T337 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2474 T337 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2477 T337 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2486 T337 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2489 T337 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2492 T337 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2494 T337 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2494 T337 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1351571028300/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty1/
[junit4:junit4]   2> 2495 T337 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f2eca1
[junit4:junit4]   2> 2496 T337 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty1/index/
[junit4:junit4]   2> 2496 T337 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2497 T337 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty1/index forceNew:false
[junit4:junit4]   2> 2501 T337 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2501 T337 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2502 T337 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2504 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2504 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2505 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2505 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2506 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2506 T337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2507 T337 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2507 T337 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2508 T337 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2509 T337 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2509 T337 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2510 T337 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2510 T337 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2511 T337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2511 T337 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2512 T337 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2512 T337 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2513 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2514 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2515 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2515 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2516 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2517 T337 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2517 T337 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2518 T337 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2520 T337 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2522 T337 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2523 T337 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SYNONYMS.TXT, CURRENCY.XML, OLD_SYNONYMS.TXT, SCHEMA.XML, OPEN-EXCHANGE-RATES.JSON, MAPPING-ISOLATIN1ACCENT.TXT, STOPWORDS.TXT, PROTWORDS.TXT]
[junit4:junit4]   2> 2526 T337 oass.SolrIndexSearcher.<init> Opening Searcher@138548d main
[junit4:junit4]   2> 2526 T337 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2527 T337 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2527 T337 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2531 T337 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2553 T372 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@138548d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2557 T337 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2758 T358 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2759 T358 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:43471_solr",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:43471/solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 2759 T358 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 2762 T371 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2762 T357 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3059 T337 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3059 T337 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:43471/solr shardId:shard1
[junit4:junit4]   2> 3060 T337 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3066 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0003 type:delete cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3066 T337 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3067 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3068 T337 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3068 T337 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3069 T337 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:43471/solr/collection1/
[junit4:junit4]   2> 3069 T337 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3069 T337 oasc.SyncStrategy.syncToMe http://127.0.0.1:43471/solr/collection1/ has no replicas
[junit4:junit4]   2> 3070 T337 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:43471/solr/collection1/
[junit4:junit4]   2> 3070 T337 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3073 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3264 T358 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3269 T357 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3269 T371 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3274 T337 oasc.ZkController.register We are http://127.0.0.1:43471/solr/collection1/ and leader is http://127.0.0.1:43471/solr/collection1/
[junit4:junit4]   2> 3275 T337 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:43471/solr
[junit4:junit4]   2> 3275 T337 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3276 T337 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3277 T337 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3278 T337 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3279 T337 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3279 T337 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3344 T337 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3347 T337 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55384
[junit4:junit4]   2> 3348 T337 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3348 T337 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785
[junit4:junit4]   2> 3348 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785/'
[junit4:junit4]   2> 3362 T337 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3362 T337 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3362 T337 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785
[junit4:junit4]   2> 3363 T337 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785/solr.xml
[junit4:junit4]   2> 3363 T337 oasc.CoreContainer.<init> New CoreContainer 24518353
[junit4:junit4]   2> 3363 T337 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785/'
[junit4:junit4]   2> 3364 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785/'
[junit4:junit4]   2> 3378 T337 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3388 T337 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:35354/solr
[junit4:junit4]   2> 3388 T337 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:35354/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@10b97da
[junit4:junit4]   2> 3389 T382 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:35354
[junit4:junit4]   2> 3389 T337 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3390 T382 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:35354, initiating session
[junit4:junit4]   2> 3390 T339 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:35756
[junit4:junit4]   2> 3390 T339 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:35756
[junit4:junit4]   2> 3391 T341 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aafe9324c0004 with negotiated timeout 5000 for client /127.0.0.1:35756
[junit4:junit4]   2> 3391 T382 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:35354, sessionid = 0x13aafe9324c0004, negotiated timeout = 5000
[junit4:junit4]   2> 3392 T383 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10b97da name:ZooKeeperConnection Watcher:127.0.0.1:35354/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3392 T337 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3392 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3393 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3394 T337 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55384_solr
[junit4:junit4]   2> 3394 T342 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aafe9324c0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55384_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55384_solr
[junit4:junit4]   2> 3395 T337 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55384_solr
[junit4:junit4]   2> 3397 T371 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3397 T357 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3398 T337 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3398 T337 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3398 T337 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3399 T337 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3399 T337 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3400 T337 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3400 T337 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3400 T337 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3401 T337 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3401 T337 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3406 T337 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3410 T337 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785/collection1
[junit4:junit4]   2> 3411 T337 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3411 T337 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3412 T337 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3413 T337 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785/collection1/'
[junit4:junit4]   2> 3413 T337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty2-1351571029785/collection1/lib/README' to classloader
[junit4:junit4]   2> 3414 T337 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty2-1351571029785/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3454 T337 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 3501 T337 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3502 T337 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3506 T337 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3773 T358 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3773 T358 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:43471_solr",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:43471/solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"active"}
[junit4:junit4]   2> 3776 T383 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3776 T371 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3776 T357 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3925 T337 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3933 T337 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3937 T337 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3946 T337 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3949 T337 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3952 T337 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3953 T337 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3954 T337 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1351571029785/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty2/
[junit4:junit4]   2> 3954 T337 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f2eca1
[junit4:junit4]   2> 3955 T337 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty2/index/
[junit4:junit4]   2> 3956 T337 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3956 T337 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty2/index forceNew:false
[junit4:junit4]   2> 3961 T337 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3962 T337 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3962 T337 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3964 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3964 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3965 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3965 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3966 T337 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3966 T337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3967 T337 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3968 T337 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3969 T337 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3969 T337 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3970 T337 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3970 T337 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3971 T337 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3971 T337 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3972 T337 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3972 T337 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3973 T337 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3974 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3974 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3975 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3975 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3976 T337 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3977 T337 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3977 T337 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3978 T337 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3980 T337 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3982 T337 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3983 T337 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SYNONYMS.TXT, CURRENCY.XML, OLD_SYNONYMS.TXT, SCHEMA.XML, OPEN-EXCHANGE-RATES.JSON, MAPPING-ISOLATIN1ACCENT.TXT, STOPWORDS.TXT, PROTWORDS.TXT]
[junit4:junit4]   2> 3986 T337 oass.SolrIndexSearcher.<init> Opening Searcher@1ca1309 main
[junit4:junit4]   2> 3986 T337 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3987 T337 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3987 T337 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3991 T337 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4020 T384 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca1309 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4025 T337 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4280 T358 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4281 T358 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55384_solr",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55384/solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 4285 T357 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4285 T371 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4285 T383 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4527 T337 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4527 T337 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55384/solr shardId:shard1
[junit4:junit4]   2> 4532 T337 oasc.ZkController.register We are http://127.0.0.1:55384/solr/collection1/ and leader is http://127.0.0.1:43471/solr/collection1/
[junit4:junit4]   2> 4533 T337 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55384/solr
[junit4:junit4]   2> 4533 T337 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 4534 T337 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C15 name=collection1 org.apache.solr.core.SolrCore@33d98c url=http://127.0.0.1:55384/solr/collection1 node=127.0.0.1:55384_solr
[junit4:junit4]   2> 4536 T385 C15 P55384 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 4537 T385 C15 P55384 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 4536 T337 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4537 T385 C15 P55384 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4538 T337 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4539 T337 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4539 T385 C15 P55384 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:43471/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 4540 T385 C15 P55384 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55384/solr START replicas=[http://127.0.0.1:43471/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 4540 T337 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4541 T385 C15 P55384 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 4543 T385 C15 P55384 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 4543 T385 C15 P55384 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 4544 T385 C15 P55384 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4549 T337 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:35354/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@c19b2c
[junit4:junit4]   2> 4550 T387 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:35354
[junit4:junit4]   2> 4550 T337 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4551 T387 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:35354, initiating session
[junit4:junit4]   2> 4551 T339 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:35759
[junit4:junit4]   2> 4552 T339 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:35759
[junit4:junit4]   2> ASYNC  NEW_CORE C16 name=collection1 org.apache.solr.core.SolrCore@69e8bc url=http://127.0.0.1:43471/solr/collection1 node=127.0.0.1:43471_solr
[junit4:junit4]   2> 4553 T369 C16 P43471 REQ /get {version=2&getVersions=100&qt=/get&distrib=false&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 4553 T341 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aafe9324c0005 with negotiated timeout 10000 for client /127.0.0.1:35759
[junit4:junit4]   2> 4553 T387 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:35354, sessionid = 0x13aafe9324c0005, negotiated timeout = 10000
[junit4:junit4]   2> 4554 T388 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c19b2c name:ZooKeeperConnection Watcher:127.0.0.1:35354/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4555 T337 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4556 T337 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4558 T337 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4559 T337 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 4564 T337 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C17 name=collection1 org.apache.solr.core.SolrCore@15a973 url=http://127.0.0.1:46411/solr/collection1 node=127.0.0.1:46411_solr
[junit4:junit4]   2> 4580 T354 C17 P46411 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1351571026505/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4580 T354 C17 P46411 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4583 T355 C17 P46411 /update {wt=javabin&version=2} {add=[1 (1417224945485742081)]} 0 5
[junit4:junit4]   2> 4583 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10001 (1417224945485742080)]} 0 5
[junit4:junit4]   2> 4583 T389 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:35354/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2d4c7f
[junit4:junit4]   2> 4584 T391 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:35354
[junit4:junit4]   2> 4584 T389 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4585 T391 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:35354, initiating session
[junit4:junit4]   2> 4585 T339 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:35762
[junit4:junit4]   2> 4585 T339 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:35762
[junit4:junit4]   2> 4586 T341 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aafe9324c0006 with negotiated timeout 10000 for client /127.0.0.1:35762
[junit4:junit4]   2> 4586 T391 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:35354, sessionid = 0x13aafe9324c0006, negotiated timeout = 10000
[junit4:junit4]   2> 4586 T392 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2d4c7f name:ZooKeeperConnection Watcher:127.0.0.1:35354/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4586 T389 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4587 T389 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4593 T368 C16 P43471 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4593 T368 C16 P43471 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4595 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10001 (1417224945499373569)]} 0 4
[junit4:junit4]   2> 4595 T368 C16 P43471 /update {wt=javabin&version=2} {add=[1 (1417224945499373568)]} 0 4
[junit4:junit4]   2> 4598 T352 C17 P46411 /update {wt=javabin&version=2} {add=[2 (1417224945506713601)]} 0 0
[junit4:junit4]   2> 4599 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10002 (1417224945506713600)]} 0 1
[junit4:junit4]   2> 4601 T369 C16 P43471 /update {wt=javabin&version=2} {add=[2 (1417224945508810752)]} 0 1
[junit4:junit4]   2> 4601 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10002 (1417224945508810753)]} 0 1
[junit4:junit4]   2> 4603 T355 C17 P46411 /update {wt=javabin&version=2} {add=[3 (1417224945511956480)]} 0 0
[junit4:junit4]   2> 4604 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10003 (1417224945511956481)]} 0 1
[junit4:junit4]   2> 4606 T368 C16 P43471 /update {wt=javabin&version=2} {add=[3 (1417224945514053632)]} 0 1
[junit4:junit4]   2> 4606 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10003 (1417224945515102208)]} 0 0
[junit4:junit4]   2> 4608 T352 C17 P46411 /update {wt=javabin&version=2} {add=[4 (1417224945517199360)]} 0 1
[junit4:junit4]   2> 4609 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10004 (1417224945517199361)]} 0 1
[junit4:junit4]   2> 4611 T366 C16 P43471 /update {wt=javabin&version=2} {add=[4 (1417224945519296512)]} 0 1
[junit4:junit4]   2> 4612 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10004 (1417224945520345088)]} 0 1
[junit4:junit4]   2> 4613 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[2 (-1417224945522442240)]} 0 0
[junit4:junit4]   2> 4615 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10002 (-1417224945523490816)]} 0 1
[junit4:junit4]   2> 4615 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[2 (-1417224945524539392)]} 0 0
[junit4:junit4]   2> 4617 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10002 (-1417224945526636544)]} 0 0
[junit4:junit4]   2> 4618 T352 C17 P46411 /update {wt=javabin&version=2} {add=[5 (1417224945526636544)]} 0 1
[junit4:junit4]   2> 4621 T368 C16 P43471 /update {wt=javabin&version=2} {add=[5 (1417224945529782272)]} 0 1
[junit4:junit4]   2> 4623 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[3 (-1417224945532928000)]} 0 0
[junit4:junit4]   2> 4626 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[3 (-1417224945536073728)]} 0 1
[junit4:junit4]   2> 4631 T354 C17 P46411 /update {wt=javabin&version=2} {add=[6 (1417224945538170880)]} 0 3
[junit4:junit4]   2> 4634 T366 C16 P43471 /update {wt=javabin&version=2} {add=[6 (1417224945543413760)]} 0 1
[junit4:junit4]   2> 4637 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[5 (-1417224945547608064)]} 0 0
[junit4:junit4]   2> 4639 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[5 (-1417224945549705216)]} 0 0
[junit4:junit4]   2> 4641 T355 C17 P46411 /update {wt=javabin&version=2} {add=[7 (1417224945551802368)]} 0 0
[junit4:junit4]   2> 4644 T369 C16 P43471 /update {wt=javabin&version=2} {add=[7 (1417224945553899520)]} 0 1
[junit4:junit4]   2> 4646 T354 C17 P46411 /update {wt=javabin&version=2} {add=[8 (1417224945557045248)]} 0 0
[junit4:junit4]   2> 4648 T366 C16 P43471 /update {wt=javabin&version=2} {add=[8 (1417224945559142400)]} 0 0
[junit4:junit4]   2> 4650 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[6 (-1417224945561239552)]} 0 0
[junit4:junit4]   2> 4651 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10005 (1417224945529782272)]} 0 32
[junit4:junit4]   2> 4652 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[6 (-1417224945563336704)]} 0 0
[junit4:junit4]   2> 4654 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10005 (1417224945564385280)]} 0 1
[junit4:junit4]   2> 4656 T355 C17 P46411 /update {wt=javabin&version=2} {add=[9 (1417224945566482432)]} 0 1
[junit4:junit4]   2> 4656 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10003 (-1417224945567531008)]} 0 0
[junit4:junit4]   2> 4659 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10003 (-1417224945570676736)]} 0 1
[junit4:junit4]   2> 4662 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10006 (1417224945572773888)]} 0 1
[junit4:junit4]   2> 4674 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10006 (1417224945575919616)]} 0 10
[junit4:junit4]   2> 4676 T366 C16 P43471 /update {wt=javabin&version=2} {add=[9 (1417224945569628160)]} 0 18
[junit4:junit4]   2> 4677 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10005 (-1417224945589551104)]} 0 0
[junit4:junit4]   2> 4679 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10 (1417224945590599680)]} 0 1
[junit4:junit4]   2> 4679 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10005 (-1417224945591648256)]} 0 0
[junit4:junit4]   2> 4682 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10 (1417224945593745408)]} 0 1
[junit4:junit4]   2> 4682 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10007 (1417224945593745408)]} 0 1
[junit4:junit4]   2> 4684 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10007 (1417224945596891136)]} 0 1
[junit4:junit4]   2> 4684 T352 C17 P46411 /update {wt=javabin&version=2} {add=[11 (1417224945596891136)]} 0 1
[junit4:junit4]   2> 4687 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10008 (1417224945600036864)]} 0 1
[junit4:junit4]   2> 4688 T368 C16 P43471 /update {wt=javabin&version=2} {add=[11 (1417224945600036864)]} 0 1
[junit4:junit4]   2> 4691 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10008 (1417224945603182592)]} 0 1
[junit4:junit4]   2> 4692 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10006 (-1417224945605279744)]} 0 0
[junit4:junit4]   2> 4694 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10006 (-1417224945607376896)]} 0 0
[junit4:junit4]   2> 4700 T355 C17 P46411 /update {wt=javabin&version=2} {add=[12 (1417224945603182592)]} 0 10
[junit4:junit4]   2> 4700 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10009 (1417224945610522624)]} 0 3
[junit4:junit4]   2> 4704 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10009 (1417224945616814080)]} 0 2
[junit4:junit4]   2> 4704 T369 C16 P43471 /update {wt=javabin&version=2} {add=[12 (1417224945616814081)]} 0 2
[junit4:junit4]   2> 4708 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10010 (1417224945619959808)]} 0 2
[junit4:junit4]   2> 4708 T354 C17 P46411 /update {wt=javabin&version=2} {add=[13 (1417224945621008384)]} 0 1
[junit4:junit4]   2> 4711 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10010 (1417224945624154112)]} 0 1
[junit4:junit4]   2> 4713 T368 C16 P43471 /update {wt=javabin&version=2} {add=[13 (1417224945626251264)]} 0 2
[junit4:junit4]   2> 4715 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[12 (-1417224945629396992)]} 0 0
[junit4:junit4]   2> 4717 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10011 (1417224945628348416)]} 0 3
[junit4:junit4]   2> 4718 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[12 (-1417224945632542720)]} 0 0
[junit4:junit4]   2> 4720 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10011 (1417224945633591296)]} 0 1
[junit4:junit4]   2> 4722 T353 C17 P46411 /update {wt=javabin&version=2} {add=[14 (1417224945635688448)]} 0 1
[junit4:junit4]   2> 4724 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10012 (1417224945637785600)]} 0 2
[junit4:junit4]   2> 4727 T368 C16 P43471 /update {wt=javabin&version=2} {add=[14 (1417224945639882752)]} 0 2
[junit4:junit4]   2> 4728 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10012 (1417224945640931328)]} 0 2
[junit4:junit4]   2> 4731 T352 C17 P46411 /update {wt=javabin&version=2} {add=[15 (1417224945645125632)]} 0 1
[junit4:junit4]   2> 4731 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10013 (1417224945645125633)]} 0 1
[junit4:junit4]   2> 4734 T366 C16 P43471 /update {wt=javabin&version=2} {add=[15 (1417224945648271360)]} 0 1
[junit4:junit4]   2> 4735 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10013 (1417224945649319936)]} 0 1
[junit4:junit4]   2> 4737 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10012 (-1417224945652465665)]} 0 0
[junit4:junit4]   2> 4738 T353 C17 P46411 /update {wt=javabin&version=2} {add=[16 (1417224945652465664)]} 0 1
[junit4:junit4]   2> 4740 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10012 (-1417224945655611392)]} 0 0
[junit4:junit4]   2> 4755 T366 C16 P43471 /update {wt=javabin&version=2} {add=[16 (1417224945656659968)]} 0 14
[junit4:junit4]   2> 4758 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[15 (-1417224945674485760)]} 0 1
[junit4:junit4]   2> 4759 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10014 (1417224945657708544)]} 0 17
[junit4:junit4]   2> 4760 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[15 (-1417224945676582912)]} 0 0
[junit4:junit4]   2> 4764 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10014 (1417224945677631488)]} 0 3
[junit4:junit4]   2> 4766 T354 C17 P46411 /update {wt=javabin&version=2} {add=[17 (1417224945679728640)]} 0 3
[junit4:junit4]   2> 4767 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10015 (1417224945682874368)]} 0 1
[junit4:junit4]   2> 4769 T366 C16 P43471 /update {wt=javabin&version=2} {add=[17 (1417224945684971520)]} 0 1
[junit4:junit4]   2> 4773 T355 C17 P46411 /update {wt=javabin&version=2} {add=[18 (1417224945689165824)]} 0 1
[junit4:junit4]   2> 4776 T369 C16 P43471 /update {wt=javabin&version=2} {add=[18 (1417224945692311552)]} 0 1
[junit4:junit4]   2> 4779 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[18 (-1417224945696505856)]} 0 1
[junit4:junit4]   2> 4782 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[18 (-1417224945698603008)]} 0 1
[junit4:junit4]   2> 4785 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10015 (1417224945687068672)]} 0 15
[junit4:junit4]   2> 4786 T354 C17 P46411 /update {wt=javabin&version=2} {add=[19 (1417224945702797312)]} 0 1
[junit4:junit4]   2> 4789 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10016 (1417224945705943040)]} 0 2
[junit4:junit4]   2> 4789 T369 C16 P43471 /update {wt=javabin&version=2} {add=[19 (1417224945706991616)]} 0 1
[junit4:junit4]   2> 4789 T358 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4791 T358 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55384_solr",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55384/solr",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"recovering"}
[junit4:junit4]   2> 4792 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[19 (-1417224945710137344)]} 0 0
[junit4:junit4]   2> 4793 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10016 (1417224945710137344)]} 0 2
[junit4:junit4]   2> 4795 T371 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4795 T357 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4795 T383 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4795 T388 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4795 T392 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4795 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[19 (-1417224945713283072)]} 0 0
[junit4:junit4]   2> 4795 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[10015 (-1417224945713283072)]} 0 0
[junit4:junit4]   2> 4799 T354 C17 P46411 /update {wt=javabin&version=2} {add=[20 (1417224945716428800)]} 0 1
[junit4:junit4]   2>  C15_STATE=coll:collection1 core:collection1 props:{collection=collection1, node_name=127.0.0.1:55384_solr, shard=shard1, base_url=http://127.0.0.1:55384/solr, roles=null, core=collection1, state=recovering}
[junit4:junit4]   2> 4811 T379 C15 P55384 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1351571026505/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4812 T379 C15 P55384 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4814 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 4815 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10015 (-1417224945717477376)]} 0 16
[junit4:junit4]   2> 4816 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 4817 T366 C16 P43471 /update {wt=javabin&version=2} {add=[20 (1417224945719574528)]} 0 16
[junit4:junit4]   2> 4819 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10017 (1417224945737400320)]} 0 1
[junit4:junit4]   2> 4820 T355 C17 P46411 /update {wt=javabin&version=2} {add=[21 (1417224945739497472)]} 0 0
[junit4:junit4]   2> 4824 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4825 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10017 (1417224945740546048)]} 0 4
[junit4:junit4]   2> 4825 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4826 T369 C16 P43471 /update {wt=javabin&version=2} {add=[21 (1417224945741594624)]} 0 4
[junit4:junit4]   2> 4827 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10018 (1417224945746837504)]} 0 0
[junit4:junit4]   2> 4828 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[21 (-1417224945746837505)]} 0 0
[junit4:junit4]   2> 4831 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4832 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[21 (-1417224945748934657)]} 0 3
[junit4:junit4]   2> 4832 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4832 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10018 (1417224945748934656)]} 0 3
[junit4:junit4]   2> 4834 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[10018 (-1417224945754177536)]} 0 0
[junit4:junit4]   2> 4839 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4839 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10018 (-1417224945756274688)]} 0 3
[junit4:junit4]   2> 4844 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10019 (1417224945762566144)]} 0 2
[junit4:junit4]   2> 4848 T353 C17 P46411 /update {wt=javabin&version=2} {add=[22 (1417224945753128960)]} 0 15
[junit4:junit4]   2> 4850 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4851 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10019 (1417224945767809024)]} 0 5
[junit4:junit4]   2> 4854 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10019 (-1417224945774100480)]} 0 1
[junit4:junit4]   2> 4854 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4855 T366 C16 P43471 /update {wt=javabin&version=2} {add=[22 (1417224945770954752)]} 0 5
[junit4:junit4]   2> 4858 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4858 T355 C17 P46411 /update {wt=javabin&version=2} {add=[23 (1417224945778294784)]} 0 1
[junit4:junit4]   2> 4859 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10019 (-1417224945777246208)]} 0 3
[junit4:junit4]   2> 4862 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10020 (1417224945782489088)]} 0 1
[junit4:junit4]   2> 4865 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4865 T368 C16 P43471 /update {wt=javabin&version=2} {add=[23 (1417224945782489088)]} 0 4
[junit4:junit4]   2> 4868 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[22 (-1417224945789829120)]} 0 0
[junit4:junit4]   2> 4873 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4874 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[22 (-1417224945792974848)]} 0 4
[junit4:junit4]   2> 4878 T354 C17 P46411 /update {wt=javabin&version=2} {add=[24 (1417224945799266304)]} 0 1
[junit4:junit4]   2> 4881 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4882 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10020 (1417224945786683392)]} 0 17
[junit4:junit4]   2> 4884 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4885 T368 C16 P43471 /update {wt=javabin&version=2} {add=[24 (1417224945803460608)]} 0 5
[junit4:junit4]   2> 4885 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10021 (1417224945807654912)]} 0 0
[junit4:junit4]   2> 4888 T352 C17 P46411 /update {wt=javabin&version=2} {add=[25 (1417224945810800640)]} 0 1
[junit4:junit4]   2> 4896 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 4897 T366 C16 P43471 /update {wt=javabin&version=2} {add=[25 (1417224945813946368)]} 0 6
[junit4:junit4]   2> 4900 T353 C17 P46411 /update {wt=javabin&version=2} {add=[26 (1417224945823383552)]} 0 1
[junit4:junit4]   2> 4906 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=13 
[junit4:junit4]   2> 4907 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4907 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10021 (1417224945810800640)]} 0 19
[junit4:junit4]   2> 4907 T368 C16 P43471 /update {wt=javabin&version=2} {add=[26 (1417224945826529280)]} 0 4
[junit4:junit4]   2> 4910 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10021 (-1417224945832820736)]} 0 1
[junit4:junit4]   2> 4911 T355 C17 P46411 /update {wt=javabin&version=2} {add=[27 (1417224945833869312)]} 0 1
[junit4:junit4]   2> 4914 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4915 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10021 (-1417224945835966464)]} 0 3
[junit4:junit4]   2> 4917 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4918 T369 C16 P43471 /update {wt=javabin&version=2} {add=[27 (1417224945838063616)]} 0 5
[junit4:junit4]   2> 4919 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10022 (1417224945842257920)]} 0 1
[junit4:junit4]   2> 4925 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4926 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10022 (1417224945845403648)]} 0 5
[junit4:junit4]   2> 4930 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10023 (1417224945852743680)]} 0 2
[junit4:junit4]   2> 4934 T353 C17 P46411 /update {wt=javabin&version=2} {add=[28 (1417224945845403648)]} 0 13
[junit4:junit4]   2> 4937 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4938 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10023 (1417224945857986560)]} 0 5
[junit4:junit4]   2> 4941 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4941 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[10022 (-1417224945866375168)]} 0 1
[junit4:junit4]   2> 4941 T369 C16 P43471 /update {wt=javabin&version=2} {add=[28 (1417224945862180864)]} 0 5
[junit4:junit4]   2> 4944 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[26 (-1417224945869520896)]} 0 0
[junit4:junit4]   2> 4946 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4946 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10022 (-1417224945868472320)]} 0 3
[junit4:junit4]   2> 4949 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4949 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[26 (-1417224945871618048)]} 0 3
[junit4:junit4]   2> 4950 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10024 (1417224945874763776)]} 0 1
[junit4:junit4]   2> 4953 T353 C17 P46411 /update {wt=javabin&version=2} {add=[29 (1417224945877909504)]} 0 1
[junit4:junit4]   2> 4956 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4957 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10024 (1417224945877909504)]} 0 5
[junit4:junit4]   2> 4959 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4960 T368 C16 P43471 /update {wt=javabin&version=2} {add=[29 (1417224945881055232)]} 0 5
[junit4:junit4]   2> 4960 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10025 (1417224945885249536)]} 0 1
[junit4:junit4]   2> 4962 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[27 (-1417224945888395264)]} 0 0
[junit4:junit4]   2> 4966 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4967 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4967 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10025 (1417224945889443840)]} 0 4
[junit4:junit4]   2> 4968 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[27 (-1417224945891540992)]} 0 3
[junit4:junit4]   2> 4971 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10026 (1417224945896783872)]} 0 1
[junit4:junit4]   2> 4971 T355 C17 P46411 /update {wt=javabin&version=2} {add=[30 (1417224945896783873)]} 0 1
[junit4:junit4]   2> 4979 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 4980 T366 C16 P43471 /update {wt=javabin&version=2} {add=[30 (1417224945900978176)]} 0 6
[junit4:junit4]   2> 4983 T354 C17 P46411 /update {wt=javabin&version=2} {add=[31 (1417224945909366784)]} 0 1
[junit4:junit4]   2> 4989 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=13 
[junit4:junit4]   2> 4990 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10026 (1417224945899929600)]} 0 17
[junit4:junit4]   2> 4990 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 4991 T369 C16 P43471 /update {wt=javabin&version=2} {add=[31 (1417224945913561088)]} 0 6
[junit4:junit4]   2> 4993 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10027 (1417224945919852544)]} 0 1
[junit4:junit4]   2> 4994 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[29 (-1417224945921949696)]} 0 1
[junit4:junit4]   2> 4998 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 4998 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[29 (-1417224945924046848)]} 0 2
[junit4:junit4]   2> 5000 T355 C17 P46411 /update {wt=javabin&version=2} {add=[32 (1417224945928241152)]} 0 0
[junit4:junit4]   2> 5004 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5005 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10027 (1417224945922998272)]} 0 10
[junit4:junit4]   2> 5007 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5008 T369 C16 P43471 /update {wt=javabin&version=2} {add=[32 (1417224945930338304)]} 0 6
[junit4:junit4]   2> 5011 T352 C17 P46411 /update {wt=javabin&version=2} {add=[33 (1417224945938726912)]} 0 1
[junit4:junit4]   2> 5016 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5016 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10028 (1417224945935581184)]} 0 9
[junit4:junit4]   2> 5017 T368 C16 P43471 /update {wt=javabin&version=2} {add=[33 (1417224945941872640)]} 0 4
[junit4:junit4]   2> 5020 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[30 (-1417224945949212672)]} 0 0
[junit4:junit4]   2> 5024 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5024 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10028 (1417224945948164096)]} 0 5
[junit4:junit4]   2> 5025 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5026 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[30 (-1417224945952358400)]} 0 3
[junit4:junit4]   2> 5027 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[10026 (-1417224945956552704)]} 0 0
[junit4:junit4]   2> 5029 T352 C17 P46411 /update {wt=javabin&version=2} {add=[34 (1417224945957601280)]} 0 1
[junit4:junit4]   2> 5032 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5032 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10026 (-1417224945958649856)]} 0 3
[junit4:junit4]   2> 5035 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5036 T366 C16 P43471 /update {wt=javabin&version=2} {add=[34 (1417224945961795584)]} 0 5
[junit4:junit4]   2> 5036 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10029 (1417224945964941312)]} 0 1
[junit4:junit4]   2> 5043 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[31 (-1417224945973329920)]} 0 0
[junit4:junit4]   2> 5047 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5047 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10029 (1417224945973329920)]} 0 4
[junit4:junit4]   2> 5048 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5049 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[31 (-1417224945976475648)]} 0 3
[junit4:junit4]   2> 5050 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[10027 (-1417224945980669952)]} 0 0
[junit4:junit4]   2> 5052 T352 C17 P46411 /update {wt=javabin&version=2} {add=[35 (1417224945981718528)]} 0 1
[junit4:junit4]   2> 5054 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5055 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10027 (-1417224945982767104)]} 0 3
[junit4:junit4]   2> 5058 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5059 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10030 (1417224945989058560)]} 0 1
[junit4:junit4]   2> 5059 T369 C16 P43471 /update {wt=javabin&version=2} {add=[35 (1417224945985912832)]} 0 5
[junit4:junit4]   2> 5063 T353 C17 P46411 /update {wt=javabin&version=2} {add=[36 (1417224945993252864)]} 0 2
[junit4:junit4]   2> 5079 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5080 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10030 (1417224945992204288)]} 0 19
[junit4:junit4]   2> 5081 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=13 
[junit4:junit4]   2> 5082 T366 C16 P43471 /update {wt=javabin&version=2} {add=[36 (1417224945997447168)]} 0 17
[junit4:junit4]   2> 5083 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10031 (1417224946014224384)]} 0 1
[junit4:junit4]   2> 5085 T352 C17 P46411 /update {wt=javabin&version=2} {add=[37 (1417224946016321536)]} 0 1
[junit4:junit4]   2> 5089 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5090 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10031 (1417224946018418688)]} 0 5
[junit4:junit4]   2> 5092 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5093 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10029 (-1417224946025758720)]} 0 1
[junit4:junit4]   2> 5093 T368 C16 P43471 /update {wt=javabin&version=2} {add=[37 (1417224946019467264)]} 0 6
[junit4:junit4]   2> 5096 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[37 (-1417224946028904448)]} 0 0
[junit4:junit4]   2> 5098 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5098 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10029 (-1417224946027855872)]} 0 3
[junit4:junit4]   2> 5101 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5101 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[37 (-1417224946032050176)]} 0 3
[junit4:junit4]   2> 5102 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10032 (1417224946034147328)]} 0 1
[junit4:junit4]   2> 5108 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5109 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10032 (1417224946037293056)]} 0 5
[junit4:junit4]   2> 5114 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10033 (1417224946045681664)]} 0 2
[junit4:junit4]   2> 5117 T352 C17 P46411 /update {wt=javabin&version=2} {add=[38 (1417224946037293056)]} 0 13
[junit4:junit4]   2> 5120 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5121 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10033 (1417224946050924544)]} 0 4
[junit4:junit4]   2> 5123 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5124 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10030 (-1417224946058264576)]} 0 0
[junit4:junit4]   2> 5124 T369 C16 P43471 /update {wt=javabin&version=2} {add=[38 (1417224946054070272)]} 0 4
[junit4:junit4]   2> 5128 T355 C17 P46411 /update {wt=javabin&version=2} {add=[39 (1417224946061410304)]} 0 1
[junit4:junit4]   2> 5129 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5130 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10030 (-1417224946061410304)]} 0 3
[junit4:junit4]   2> 5134 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10034 (1417224946066653184)]} 0 2
[junit4:junit4]   2> 5142 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5142 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10034 (1417224946070847488)]} 0 6
[junit4:junit4]   2> 5145 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[10031 (-1417224946080284672)]} 0 0
[junit4:junit4]   2> 5147 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5148 T366 C16 P43471 /update {wt=javabin&version=2} {add=[39 (1417224946064556032)]} 0 18
[junit4:junit4]   2> 5150 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5151 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10031 (-1417224946083430400)]} 0 4
[junit4:junit4]   2> 5152 T353 C17 P46411 /update {wt=javabin&version=2} {add=[40 (1417224946085527552)]} 0 2
[junit4:junit4]   2> 5154 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10035 (1417224946089721856)]} 0 1
[junit4:junit4]   2> 5157 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5158 T369 C16 P43471 /update {wt=javabin&version=2} {add=[40 (1417224946089721856)]} 0 4
[junit4:junit4]   2> 5161 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[40 (-1417224946097061888)]} 0 0
[junit4:junit4]   2> 5165 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5166 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[40 (-1417224946099159040)]} 0 3
[junit4:junit4]   2> 5170 T352 C17 P46411 /update {wt=javabin&version=2} {add=[41 (1417224946105450496)]} 0 1
[junit4:junit4]   2> 5173 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=14 
[junit4:junit4]   2> 5174 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10035 (1417224946092867584)]} 0 17
[junit4:junit4]   2> 5177 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5178 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10036 (1417224946113839104)]} 0 1
[junit4:junit4]   2> 5178 T369 C16 P43471 /update {wt=javabin&version=2} {add=[41 (1417224946108596224)]} 0 6
[junit4:junit4]   2> 5181 T355 C17 P46411 /update {wt=javabin&version=2} {add=[42 (1417224946118033408)]} 0 0
[junit4:junit4]   2> 5184 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5184 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10036 (1417224946116984832)]} 0 4
[junit4:junit4]   2> 5187 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5188 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10037 (1417224946124324864)]} 0 1
[junit4:junit4]   2> 5188 T366 C16 P43471 /update {wt=javabin&version=2} {add=[42 (1417224946121179136)]} 0 4
[junit4:junit4]   2> 5194 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5195 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10037 (1417224946127470592)]} 0 5
[junit4:junit4]   2> 5198 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10037 (-1417224946134810624)]} 0 1
[junit4:junit4]   2> 5202 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5203 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10037 (-1417224946137956352)]} 0 3
[junit4:junit4]   2> 5208 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10038 (1417224946144247808)]} 0 2
[junit4:junit4]   2> 5214 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5215 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10038 (1417224946149490688)]} 0 5
[junit4:junit4]   2> 5217 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10039 (1417224946155782144)]} 0 0
[junit4:junit4]   2> 5221 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5222 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10039 (1417224946157879296)]} 0 3
[junit4:junit4]   2> 5224 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10040 (1417224946163122176)]} 0 1
[junit4:junit4]   2> 5228 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5229 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10040 (1417224946165219328)]} 0 3
[junit4:junit4]   2> 5230 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[10040 (-1417224946169413632)]} 0 0
[junit4:junit4]   2> 5234 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5234 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10040 (-1417224946171510784)]} 0 2
[junit4:junit4]   2> 5236 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10041 (1417224946175705088)]} 0 0
[junit4:junit4]   2> 5241 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5241 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10041 (1417224946177802240)]} 0 3
[junit4:junit4]   2> 5243 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10042 (1417224946183045120)]} 0 0
[junit4:junit4]   2> 5248 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5248 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10042 (1417224946185142272)]} 0 3
[junit4:junit4]   2> 5250 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10043 (1417224946190385152)]} 0 0
[junit4:junit4]   2> 5253 T352 C17 P46411 /update {wt=javabin&version=2} {add=[43 (1417224946128519168)]} 0 62
[junit4:junit4]   2> 5274 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=14 
[junit4:junit4]   2> 5275 T369 C16 P43471 /update {wt=javabin&version=2} {add=[43 (1417224946196676608)]} 0 19
[junit4:junit4]   2> 5278 T354 C17 P46411 /update {wt=javabin&version=2} {add=[44 (1417224946218696704)]} 0 1
[junit4:junit4]   2> 5282 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5283 T368 C16 P43471 /update {wt=javabin&version=2} {add=[44 (1417224946220793856)]} 0 4
[junit4:junit4]   2> 5285 T353 C17 P46411 /update {wt=javabin&version=2} {add=[45 (1417224946227085312)]} 0 0
[junit4:junit4]   2> 5290 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5290 T369 C16 P43471 /update {wt=javabin&version=2} {add=[45 (1417224946229182464)]} 0 3
[junit4:junit4]   2> 5293 T355 C17 P46411 /update {wt=javabin&version=2} {add=[46 (1417224946234425344)]} 0 1
[junit4:junit4]   2> 5297 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5297 T368 C16 P43471 /update {wt=javabin&version=2} {add=[46 (1417224946237571072)]} 0 3
[junit4:junit4]   2> 5299 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[44 (-1417224946241765376)]} 0 0
[junit4:junit4]   2> 5302 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5303 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[44 (-1417224946243862528)]} 0 2
[junit4:junit4]   2> 5311 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5312 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10043 (1417224946192482304)]} 0 60
[junit4:junit4]   2> 5314 T354 C17 P46411 /update {wt=javabin&version=2} {add=[47 (1417224946248056832)]} 0 9
[junit4:junit4]   2> 5315 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10044 (1417224946257494016)]} 0 1
[junit4:junit4]   2> 5318 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5319 T368 C16 P43471 /update {wt=javabin&version=2} {add=[47 (1417224946259591168)]} 0 3
[junit4:junit4]   2> 5320 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5320 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10044 (1417224946260639744)]} 0 3
[junit4:junit4]   2> 5321 T355 C17 P46411 /update {wt=javabin&version=2} {add=[48 (1417224946264834048)]} 0 1
[junit4:junit4]   2> 5323 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10045 (1417224946265882624)]} 0 1
[junit4:junit4]   2> 5325 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5326 T366 C16 P43471 /update {wt=javabin&version=2} {add=[48 (1417224946266931200)]} 0 3
[junit4:junit4]   2> 5327 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5327 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10045 (1417224946269028352)]} 0 3
[junit4:junit4]   2> 5328 T354 C17 P46411 /update {wt=javabin&version=2} {add=[49 (1417224946272174080)]} 0 1
[junit4:junit4]   2> 5330 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10046 (1417224946273222656)]} 0 1
[junit4:junit4]   2> 5332 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5332 T369 C16 P43471 /update {wt=javabin&version=2} {add=[49 (1417224946274271232)]} 0 2
[junit4:junit4]   2> 5335 T355 C17 P46411 /update {wt=javabin&version=2} {add=[50 (1417224946278465536)]} 0 1
[junit4:junit4]   2> 5344 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=11 
[junit4:junit4]   2> 5345 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10046 (1417224946276368384)]} 0 14
[junit4:junit4]   2> 5347 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[10044 (-1417224946292097024)]} 0 0
[junit4:junit4]   2> 5351 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5351 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10044 (-1417224946294194176)]} 0 2
[junit4:junit4]   2> 5354 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10047 (1417224946298388480)]} 0 1
[junit4:junit4]   2> 5356 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5356 T368 C16 P43471 /update {wt=javabin&version=2} {add=[50 (1417224946281611264)]} 0 19
[junit4:junit4]   2> 5358 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[50 (-1417224946303631360)]} 0 0
[junit4:junit4]   2> 5359 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5359 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10047 (1417224946301534208)]} 0 3
[junit4:junit4]   2> 5361 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10048 (1417224946306777088)]} 0 0
[junit4:junit4]   2> 5362 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5363 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[50 (-1417224946305728512)]} 0 3
[junit4:junit4]   2> 5366 T354 C17 P46411 /update {wt=javabin&version=2} {add=[51 (1417224946310971392)]} 0 1
[junit4:junit4]   2> 5367 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5367 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10048 (1417224946309922816)]} 0 4
[junit4:junit4]   2> 5370 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5370 T366 C16 P43471 /update {wt=javabin&version=2} {add=[51 (1417224946314117120)]} 0 2
[junit4:junit4]   2> 5373 T352 C17 P46411 /update {wt=javabin&version=2} {add=[52 (1417224946318311424)]} 0 1
[junit4:junit4]   2> 5380 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10049 (1417224946315165696)]} 0 11
[junit4:junit4]   2> 5380 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5381 T368 C16 P43471 /update {wt=javabin&version=2} {add=[52 (1417224946322505728)]} 0 6
[junit4:junit4]   2> 5385 T354 C17 P46411 /update {wt=javabin&version=2} {add=[53 (1417224946330894336)]} 0 1
[junit4:junit4]   2> 5393 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5394 T366 C16 P43471 /update {wt=javabin&version=2} {add=[53 (1417224946334040064)]} 0 7
[junit4:junit4]   2> 5397 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[51 (-1417224946344525824)]} 0 0
[junit4:junit4]   2> 5400 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5400 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10049 (1417224946328797184)]} 0 18
[junit4:junit4]   2> 5402 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5402 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[51 (-1417224946347671552)]} 0 3
[junit4:junit4]   2> 5404 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10050 (1417224946350817280)]} 0 1
[junit4:junit4]   2> 5406 T353 C17 P46411 /update {wt=javabin&version=2} {add=[54 (1417224946352914432)]} 0 1
[junit4:junit4]   2> 5410 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5410 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10050 (1417224946353963008)]} 0 4
[junit4:junit4]   2> 5412 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5413 T369 C16 P43471 /update {wt=javabin&version=2} {add=[54 (1417224946356060160)]} 0 5
[junit4:junit4]   2> 5413 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10050 (-1417224946361303040)]} 0 0
[junit4:junit4]   2> 5416 T355 C17 P46411 /update {wt=javabin&version=2} {add=[55 (1417224946363400192)]} 0 1
[junit4:junit4]   2> 5418 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5419 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10050 (-1417224946364448768)]} 0 4
[junit4:junit4]   2> 5422 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10051 (1417224946369691648)]} 0 1
[junit4:junit4]   2> 5430 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=3 
[junit4:junit4]   2> 5431 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10051 (1417224946372837376)]} 0 7
[junit4:junit4]   2> 5435 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10052 (1417224946383323136)]} 0 1
[junit4:junit4]   2> 5435 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=14 
[junit4:junit4]   2> 5436 T366 C16 P43471 /update {wt=javabin&version=2} {add=[55 (1417224946366545920)]} 0 18
[junit4:junit4]   2> 5439 T354 C17 P46411 /update {wt=javabin&version=2} {add=[56 (1417224946388566016)]} 0 1
[junit4:junit4]   2> 5441 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5442 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10052 (1417224946386468864)]} 0 5
[junit4:junit4]   2> 5445 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10053 (1417224946393808896)]} 0 1
[junit4:junit4]   2> 5446 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5446 T369 C16 P43471 /update {wt=javabin&version=2} {add=[56 (1417224946391711744)]} 0 4
[junit4:junit4]   2> 5449 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[52 (-1417224946399051776)]} 0 0
[junit4:junit4]   2> 5451 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5452 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10053 (1417224946398003200)]} 0 5
[junit4:junit4]   2> 5454 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5454 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10051 (-1417224946404294656)]} 0 0
[junit4:junit4]   2> 5455 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[52 (-1417224946402197504)]} 0 3
[junit4:junit4]   2> 5459 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5460 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10051 (-1417224946407440384)]} 0 3
[junit4:junit4]   2> 5466 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10054 (1417224946413731840)]} 0 4
[junit4:junit4]   2> 5472 T354 C17 P46411 /update {wt=javabin&version=2} {add=[57 (1417224946407440384)]} 0 15
[junit4:junit4]   2> 5472 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5473 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10054 (1417224946420023296)]} 0 4
[junit4:junit4]   2> 5477 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10055 (1417224946427363328)]} 0 1
[junit4:junit4]   2> 5478 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5479 T368 C16 P43471 /update {wt=javabin&version=2} {add=[57 (1417224946425266176)]} 0 5
[junit4:junit4]   2> 5482 T353 C17 P46411 /update {wt=javabin&version=2} {add=[58 (1417224946432606208)]} 0 1
[junit4:junit4]   2> 5483 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5484 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10055 (1417224946431557632)]} 0 5
[junit4:junit4]   2> 5487 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10056 (1417224946437849088)]} 0 1
[junit4:junit4]   2> 5493 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5494 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10056 (1417224946442043392)]} 0 4
[junit4:junit4]   2> 5496 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10052 (-1417224946448334848)]} 0 0
[junit4:junit4]   2> 5498 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5498 T369 C16 P43471 /update {wt=javabin&version=2} {add=[58 (1417224946436800512)]} 0 13
[junit4:junit4]   2> 5500 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5500 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10052 (-1417224946450432000)]} 0 2
[junit4:junit4]   2> 5500 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[55 (-1417224946452529152)]} 0 0
[junit4:junit4]   2> 5502 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10057 (1417224946454626304)]} 0 0
[junit4:junit4]   2> 5504 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5504 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[55 (-1417224946454626304)]} 0 2
[junit4:junit4]   2> 5507 T355 C17 P46411 /update {wt=javabin&version=2} {add=[59 (1417224946458820608)]} 0 1
[junit4:junit4]   2> 5511 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5512 T366 C16 P43471 /update {wt=javabin&version=2} {add=[59 (1417224946461966336)]} 0 4
[junit4:junit4]   2> 5514 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[56 (-1417224946467209216)]} 0 0
[junit4:junit4]   2> 5517 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5518 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[56 (-1417224946469306368)]} 0 2
[junit4:junit4]   2> 5520 T352 C17 P46411 /update {wt=javabin&version=2} {add=[60 (1417224946473500672)]} 0 0
[junit4:junit4]   2> 5525 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5525 T366 C16 P43471 /update {wt=javabin&version=2} {add=[60 (1417224946475597824)]} 0 3
[junit4:junit4]   2> 5527 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[58 (-1417224946480840704)]} 0 0
[junit4:junit4]   2> 5531 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5531 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[58 (-1417224946482937856)]} 0 2
[junit4:junit4]   2> 5533 T355 C17 P46411 /update {wt=javabin&version=2} {add=[61 (1417224946487132160)]} 0 0
[junit4:junit4]   2> 5537 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5538 T366 C16 P43471 /update {wt=javabin&version=2} {add=[61 (1417224946489229312)]} 0 3
[junit4:junit4]   2> 5540 T354 C17 P46411 /update {wt=javabin&version=2} {add=[62 (1417224946494472192)]} 0 0
[junit4:junit4]   2> 5542 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=35 
[junit4:junit4]   2> 5543 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10057 (1417224946456723456)]} 0 39
[junit4:junit4]   2> 5545 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5545 T368 C16 P43471 /update {wt=javabin&version=2} {add=[62 (1417224946496569344)]} 0 3
[junit4:junit4]   2> 5549 T353 C17 P46411 /update {wt=javabin&version=2} {add=[63 (1417224946501812224)]} 0 2
[junit4:junit4]   2> 5555 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5556 T369 C16 P43471 /update {wt=javabin&version=2} {add=[63 (1417224946507055104)]} 0 4
[junit4:junit4]   2> 5558 T355 C17 P46411 /update {wt=javabin&version=2} {add=[64 (1417224946513346560)]} 0 0
[junit4:junit4]   2> 5560 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10058 (1417224946498666496)]} 0 16
[junit4:junit4]   2> 5562 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5562 T368 C16 P43471 /update {wt=javabin&version=2} {add=[64 (1417224946515443712)]} 0 2
[junit4:junit4]   2> 5564 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5565 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10058 (1417224946517540864)]} 0 3
[junit4:junit4]   2> 5565 T354 C17 P46411 /update {wt=javabin&version=2} {add=[65 (1417224946520686592)]} 0 1
[junit4:junit4]   2> 5567 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10055 (-1417224946521735168)]} 0 0
[junit4:junit4]   2> 5569 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5570 T366 C16 P43471 /update {wt=javabin&version=2} {add=[65 (1417224946522783744)]} 0 3
[junit4:junit4]   2> 5570 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5570 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10055 (-1417224946523832320)]} 0 2
[junit4:junit4]   2> 5572 T355 C17 P46411 /update {wt=javabin&version=2} {add=[66 (1417224946528026624)]} 0 1
[junit4:junit4]   2> 5573 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10059 (1417224946528026625)]} 0 1
[junit4:junit4]   2> 5576 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5576 T368 C16 P43471 /update {wt=javabin&version=2} {add=[66 (1417224946530123776)]} 0 2
[junit4:junit4]   2> 5579 T354 C17 P46411 /update {wt=javabin&version=2} {add=[67 (1417224946534318080)]} 0 1
[junit4:junit4]   2> 5584 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5585 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=9 
[junit4:junit4]   2> 5585 T366 C16 P43471 /update {wt=javabin&version=2} {add=[67 (1417224946537463808)]} 0 4
[junit4:junit4]   2> 5586 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10059 (1417224946531172352)]} 0 11
[junit4:junit4]   2> 5588 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[61 (-1417224946544803840)]} 0 0
[junit4:junit4]   2> 5589 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[10056 (-1417224946545852416)]} 0 1
[junit4:junit4]   2> 5593 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5593 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5593 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[61 (-1417224946546900992)]} 0 3
[junit4:junit4]   2> 5594 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10056 (-1417224946547949568)]} 0 3
[junit4:junit4]   2> 5597 T352 C17 P46411 /update {wt=javabin&version=2} {add=[68 (1417224946553192448)]} 0 1
[junit4:junit4]   2> 5597 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10060 (1417224946553192449)]} 0 1
[junit4:junit4]   2> 5603 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5604 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10060 (1417224946556338177)]} 0 5
[junit4:junit4]   2> 5606 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10058 (-1417224946563678208)]} 0 0
[junit4:junit4]   2> 5610 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5610 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10058 (-1417224946565775360)]} 0 2
[junit4:junit4]   2> 5613 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5613 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10061 (1417224946569969664)]} 0 1
[junit4:junit4]   2> 5614 T366 C16 P43471 /update {wt=javabin&version=2} {add=[68 (1417224946556338176)]} 0 15
[junit4:junit4]   2> 5619 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5619 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10061 (1417224946573115392)]} 0 4
[junit4:junit4]   2> 5623 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10062 (1417224946579406848)]} 0 2
[junit4:junit4]   2> 5626 T352 C17 P46411 /update {wt=javabin&version=2} {add=[69 (1417224946574163968)]} 0 11
[junit4:junit4]   2> 5628 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5629 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10062 (1417224946583601152)]} 0 4
[junit4:junit4]   2> 5632 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5632 T369 C16 P43471 /update {wt=javabin&version=2} {add=[69 (1417224946586746880)]} 0 4
[junit4:junit4]   2> 5632 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10063 (1417224946590941184)]} 0 0
[junit4:junit4]   2> 5635 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[62 (-1417224946594086912)]} 0 1
[junit4:junit4]   2> 5638 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5639 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10063 (1417224946594086912)]} 0 4
[junit4:junit4]   2> 5639 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5640 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[62 (-1417224946596184064)]} 0 3
[junit4:junit4]   2> 5642 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10064 (1417224946601426944)]} 0 1
[junit4:junit4]   2> 5643 T352 C17 P46411 /update {wt=javabin&version=2} {add=[70 (1417224946602475520)]} 0 0
[junit4:junit4]   2> 5648 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5649 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10064 (1417224946604572672)]} 0 4
[junit4:junit4]   2> 5649 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5650 T369 C16 P43471 /update {wt=javabin&version=2} {add=[70 (1417224946605621248)]} 0 4
[junit4:junit4]   2> 5652 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10065 (1417224946611912704)]} 0 1
[junit4:junit4]   2> 5654 T355 C17 P46411 /update {wt=javabin&version=2} {add=[71 (1417224946612961280)]} 0 1
[junit4:junit4]   2> 5661 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5662 T368 C16 P43471 /update {wt=javabin&version=2} {add=[71 (1417224946616107008)]} 0 6
[junit4:junit4]   2> 5665 T354 C17 P46411 /update {wt=javabin&version=2} {add=[72 (1417224946624495616)]} 0 1
[junit4:junit4]   2> 5670 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=12 
[junit4:junit4]   2> 5670 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10065 (1417224946615058432)]} 0 15
[junit4:junit4]   2> 5671 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5672 T367 C16 P43471 /update {wt=javabin&version=2} {add=[72 (1417224946628689920)]} 0 5
[junit4:junit4]   2> 5674 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10066 (1417224946633932800)]} 0 1
[junit4:junit4]   2> 5675 T353 C17 P46411 /update {wt=javabin&version=2} {add=[73 (1417224946634981376)]} 0 1
[junit4:junit4]   2> 5680 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5681 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10066 (1417224946637078528)]} 0 5
[junit4:junit4]   2> 5694 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5695 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10067 (1417224946644418560)]} 0 12
[junit4:junit4]   2> 5695 T368 C16 P43471 /update {wt=javabin&version=2} {add=[73 (1417224946639175680)]} 0 17
[junit4:junit4]   2> 5698 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[65 (-1417224946660147200)]} 0 0
[junit4:junit4]   2> 5703 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5704 T367 C16 P43471 /update {wt=javabin&version=2} {delete=[65 (-1417224946663292928)]} 0 3
[junit4:junit4]   2> 5709 T352 C17 P46411 /update {wt=javabin&version=2} {add=[74 (1417224946668535808)]} 0 3
[junit4:junit4]   2> 5714 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5715 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10067 (1417224946660147200)]} 0 17
[junit4:junit4]   2> 5716 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5716 T369 C16 P43471 /update {wt=javabin&version=2} {add=[74 (1417224946674827264)]} 0 4
[junit4:junit4]   2> 5717 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10061 (-1417224946680070144)]} 0 0
[junit4:junit4]   2> 5719 T355 C17 P46411 /update {wt=javabin&version=2} {add=[75 (1417224946681118720)]} 0 1
[junit4:junit4]   2> 5720 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5721 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10061 (-1417224946682167296)]} 0 3
[junit4:junit4]   2> 5723 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5723 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10068 (1417224946686361600)]} 0 1
[junit4:junit4]   2> 5723 T367 C16 P43471 /update {wt=javabin&version=2} {add=[75 (1417224946684264448)]} 0 2
[junit4:junit4]   2> 5726 T352 C17 P46411 /update {wt=javabin&version=2} {add=[76 (1417224946688458752)]} 0 1
[junit4:junit4]   2> 5728 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5729 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10068 (1417224946688458752)]} 0 4
[junit4:junit4]   2> 5730 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5731 T369 C16 P43471 /update {wt=javabin&version=2} {add=[76 (1417224946691604480)]} 0 3
[junit4:junit4]   2> 5731 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10069 (1417224946694750208)]} 0 0
[junit4:junit4]   2> 5733 T355 C17 P46411 /update {wt=javabin&version=2} {add=[77 (1417224946696847360)]} 0 0
[junit4:junit4]   2> 5738 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5738 T367 C16 P43471 /update {wt=javabin&version=2} {add=[77 (1417224946698944512)]} 0 3
[junit4:junit4]   2> 5742 T354 C17 P46411 /update {wt=javabin&version=2} {add=[78 (1417224946705235968)]} 0 1
[junit4:junit4]   2> 5744 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=8 
[junit4:junit4]   2> 5745 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10069 (1417224946696847360)]} 0 12
[junit4:junit4]   2> 5746 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[10062 (-1417224946710478848)]} 0 0
[junit4:junit4]   2> 5747 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5747 T366 C16 P43471 /update {wt=javabin&version=2} {add=[78 (1417224946708381696)]} 0 3
[junit4:junit4]   2> 5750 T353 C17 P46411 /update {wt=javabin&version=2} {add=[79 (1417224946713624576)]} 0 1
[junit4:junit4]   2> 5750 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5751 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10062 (-1417224946712576000)]} 0 3
[junit4:junit4]   2> 5753 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10070 (1417224946717818880)]} 0 0
[junit4:junit4]   2> 5754 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5755 T367 C16 P43471 /update {wt=javabin&version=2} {add=[79 (1417224946716770304)]} 0 4
[junit4:junit4]   2> 5758 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5759 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10070 (1417224946719916032)]} 0 4
[junit4:junit4]   2> 5763 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10071 (1417224946726207488)]} 0 2
[junit4:junit4]   2> 5769 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5770 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10071 (1417224946731450368)]} 0 5
[junit4:junit4]   2> 5773 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10072 (1417224946737741824)]} 0 1
[junit4:junit4]   2> 5777 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5778 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10072 (1417224946740887552)]} 0 3
[junit4:junit4]   2> 5780 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10073 (1417224946746130432)]} 0 1
[junit4:junit4]   2> 5807 T354 C17 P46411 /update {wt=javabin&version=2} {add=[80 (1417224946722013184)]} 0 50
[junit4:junit4]   2> 5813 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5813 T369 C16 P43471 /update {wt=javabin&version=2} {add=[80 (1417224946776539136)]} 0 4
[junit4:junit4]   2> 5815 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[66 (-1417224946782830592)]} 0 0
[junit4:junit4]   2> 5819 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5819 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[66 (-1417224946784927744)]} 0 2
[junit4:junit4]   2> 5822 T352 C17 P46411 /update {wt=javabin&version=2} {add=[81 (1417224946790170624)]} 0 1
[junit4:junit4]   2> 5823 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5824 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10073 (1417224946748227584)]} 0 42
[junit4:junit4]   2> 5826 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10065 (-1417224946794364928)]} 0 1
[junit4:junit4]   2> 5827 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5827 T367 C16 P43471 /update {wt=javabin&version=2} {add=[81 (1417224946792267776)]} 0 3
[junit4:junit4]   2> 5829 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5829 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[67 (-1417224946797510656)]} 0 0
[junit4:junit4]   2> 5830 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10065 (-1417224946795413504)]} 0 3
[junit4:junit4]   2> 5832 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10074 (1417224946799607808)]} 0 1
[junit4:junit4]   2> 5834 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5834 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[67 (-1417224946800656384)]} 0 3
[junit4:junit4]   2> 5837 T352 C17 P46411 /update {wt=javabin&version=2} {add=[82 (1417224946805899264)]} 0 1
[junit4:junit4]   2> 5843 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5844 T367 C16 P43471 /update {wt=javabin&version=2} {add=[82 (1417224946807996416)]} 0 5
[junit4:junit4]   2> 5844 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=8 
[junit4:junit4]   2> 5845 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10074 (1417224946802753536)]} 0 11
[junit4:junit4]   2> 5846 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[68 (-1417224946815336448)]} 0 0
[junit4:junit4]   2> 5847 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10075 (1417224946815336449)]} 0 1
[junit4:junit4]   2> 5849 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5850 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[68 (-1417224946817433600)]} 0 2
[junit4:junit4]   2> 5851 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5851 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10075 (1417224946818482176)]} 0 2
[junit4:junit4]   2> 5852 T355 C17 P46411 /update {wt=javabin&version=2} {add=[83 (1417224946821627904)]} 0 0
[junit4:junit4]   2> 5854 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10076 (1417224946822676480)]} 0 1
[junit4:junit4]   2> 5856 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5857 T367 C16 P43471 /update {wt=javabin&version=2} {add=[83 (1417224946823725056)]} 0 3
[junit4:junit4]   2> 5858 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5858 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10076 (1417224946825822208)]} 0 2
[junit4:junit4]   2> 5859 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[69 (-1417224946828967936)]} 0 0
[junit4:junit4]   2> 5863 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5863 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[69 (-1417224946831065088)]} 0 2
[junit4:junit4]   2> 5867 T355 C17 P46411 /update {wt=javabin&version=2} {add=[84 (1417224946835259392)]} 0 2
[junit4:junit4]   2> 5869 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10077 (1417224946831065088)]} 0 9
[junit4:junit4]   2> 5872 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5873 T368 C16 P43471 /update {wt=javabin&version=2} {add=[84 (1417224946839453696)]} 0 4
[junit4:junit4]   2> 5874 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5875 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10077 (1417224946842599424)]} 0 3
[junit4:junit4]   2> 5875 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[70 (-1417224946845745152)]} 0 0
[junit4:junit4]   2> 5878 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10078 (1417224946847842304)]} 0 1
[junit4:junit4]   2> 5880 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5881 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[70 (-1417224946848890880)]} 0 3
[junit4:junit4]   2> 5884 T355 C17 P46411 /update {wt=javabin&version=2} {add=[85 (1417224946854133760)]} 0 1
[junit4:junit4]   2> 5884 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5885 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10078 (1417224946852036608)]} 0 5
[junit4:junit4]   2> 5888 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10079 (1417224946858328064)]} 0 1
[junit4:junit4]   2> 5890 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5890 T368 C16 P43471 /update {wt=javabin&version=2} {add=[85 (1417224946857279488)]} 0 4
[junit4:junit4]   2> 5894 T352 C17 P46411 /update {wt=javabin&version=2} {add=[86 (1417224946864619520)]} 0 1
[junit4:junit4]   2> 5894 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5895 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10079 (1417224946862522368)]} 0 5
[junit4:junit4]   2> 5898 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10080 (1417224946868813824)]} 0 1
[junit4:junit4]   2> 5913 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 5914 T366 C16 P43471 /update {wt=javabin&version=2} {add=[86 (1417224946867765248)]} 0 18
[junit4:junit4]   2> 5916 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=13 
[junit4:junit4]   2> 5917 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10080 (1417224946871959552)]} 0 18
[junit4:junit4]   2> 5917 T355 C17 P46411 /update {wt=javabin&version=2} {add=[87 (1417224946888736768)]} 0 1
[junit4:junit4]   2> 5918 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10066 (-1417224946890833920)]} 0 0
[junit4:junit4]   2> 5922 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5922 T368 C16 P43471 /update {wt=javabin&version=2} {add=[87 (1417224946891882496)]} 0 3
[junit4:junit4]   2> 5922 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5923 T367 C16 P43471 /update {wt=javabin&version=2} {delete=[10066 (-1417224946893979648)]} 0 3
[junit4:junit4]   2> 5925 T352 C17 P46411 /update {wt=javabin&version=2} {add=[88 (1417224946897125376)]} 0 1
[junit4:junit4]   2> 5926 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10081 (1417224946898173952)]} 0 1
[junit4:junit4]   2> 5930 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5931 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5931 T366 C16 P43471 /update {wt=javabin&version=2} {add=[88 (1417224946900271104)]} 0 4
[junit4:junit4]   2> 5931 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10081 (1417224946901319680)]} 0 3
[junit4:junit4]   2> 5933 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10067 (-1417224946906562561)]} 0 0
[junit4:junit4]   2> 5938 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5939 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10067 (-1417224946909708288)]} 0 4
[junit4:junit4]   2> 5944 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10082 (1417224946914951168)]} 0 3
[junit4:junit4]   2> 5944 T355 C17 P46411 /update {wt=javabin&version=2} {add=[89 (1417224946906562560)]} 0 11
[junit4:junit4]   2> 5949 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5950 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10082 (1417224946920194048)]} 0 4
[junit4:junit4]   2> 5950 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5951 T366 C16 P43471 /update {wt=javabin&version=2} {add=[89 (1417224946921242624)]} 0 5
[junit4:junit4]   2> 5953 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10068 (-1417224946927534080)]} 0 0
[junit4:junit4]   2> 5955 T353 C17 P46411 /update {wt=javabin&version=2} {add=[90 (1417224946928582656)]} 0 1
[junit4:junit4]   2> 5958 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5958 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10068 (-1417224946929631232)]} 0 3
[junit4:junit4]   2> 5961 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5962 T368 C16 P43471 /update {wt=javabin&version=2} {add=[90 (1417224946931728384)]} 0 5
[junit4:junit4]   2> 5962 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10083 (1417224946935922688)]} 0 1
[junit4:junit4]   2> 5964 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[72 (-1417224946939068416)]} 0 0
[junit4:junit4]   2> 5968 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5969 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5969 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10083 (1417224946940116992)]} 0 5
[junit4:junit4]   2> 5970 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[72 (-1417224946942214144)]} 0 3
[junit4:junit4]   2> 5971 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10069 (-1417224946946408448)]} 0 0
[junit4:junit4]   2> 5973 T353 C17 P46411 /update {wt=javabin&version=2} {add=[91 (1417224946947457024)]} 0 1
[junit4:junit4]   2> 5976 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5977 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10069 (-1417224946949554176)]} 0 3
[junit4:junit4]   2> 5980 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 5981 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10084 (1417224946955845632)]} 0 1
[junit4:junit4]   2> 5981 T368 C16 P43471 /update {wt=javabin&version=2} {add=[91 (1417224946951651328)]} 0 5
[junit4:junit4]   2> 5984 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[74 (-1417224946960039936)]} 0 1
[junit4:junit4]   2> 5988 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 5989 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[74 (-1417224946962137088)]} 0 3
[junit4:junit4]   2> 5993 T354 C17 P46411 /update {wt=javabin&version=2} {add=[92 (1417224946968428544)]} 0 1
[junit4:junit4]   2> 5998 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=12 
[junit4:junit4]   2> 5999 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10084 (1417224946958991360)]} 0 16
[junit4:junit4]   2> 6000 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 6001 T369 C16 P43471 /update {wt=javabin&version=2} {add=[92 (1417224946971574272)]} 0 6
[junit4:junit4]   2> 6001 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10070 (-1417224946977865728)]} 0 0
[junit4:junit4]   2> 6004 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[75 (-1417224946981011456)]} 0 1
[junit4:junit4]   2> 6006 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6006 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10070 (-1417224946981011456)]} 0 3
[junit4:junit4]   2> 6009 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6009 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[75 (-1417224946983108608)]} 0 3
[junit4:junit4]   2> 6010 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10085 (1417224946986254336)]} 0 1
[junit4:junit4]   2> 6013 T354 C17 P46411 /update {wt=javabin&version=2} {add=[93 (1417224946989400064)]} 0 1
[junit4:junit4]   2> 6017 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6018 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10085 (1417224946990448640)]} 0 5
[junit4:junit4]   2> 6021 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10086 (1417224946997788672)]} 0 1
[junit4:junit4]   2> 6027 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6028 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10086 (1417224947001982976)]} 0 4
[junit4:junit4]   2> 6031 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6032 T369 C16 P43471 /update {wt=javabin&version=2} {add=[93 (1417224946992545792)]} 0 17
[junit4:junit4]   2> 6035 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[76 (-1417224947013517312)]} 0 1
[junit4:junit4]   2> 6038 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6039 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10087 (1417224947009323008)]} 0 9
[junit4:junit4]   2> 6039 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[76 (-1417224947015614464)]} 0 2
[junit4:junit4]   2> 6042 T354 C17 P46411 /update {wt=javabin&version=2} {add=[94 (1417224947019808768)]} 0 1
[junit4:junit4]   2> 6044 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6044 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10087 (1417224947019808768)]} 0 3
[junit4:junit4]   2> 6046 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10088 (1417224947025051648)]} 0 0
[junit4:junit4]   2> 6051 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6051 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10088 (1417224947027148800)]} 0 3
[junit4:junit4]   2> 6053 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6053 T368 C16 P43471 /update {wt=javabin&version=2} {add=[94 (1417224947022954496)]} 0 10
[junit4:junit4]   2> 6053 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10089 (1417224947032391680)]} 0 0
[junit4:junit4]   2> 6056 T352 C17 P46411 /update {wt=javabin&version=2} {add=[95 (1417224947034488832)]} 0 1
[junit4:junit4]   2> 6059 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6060 T366 C16 P43471 /update {wt=javabin&version=2} {add=[10089 (1417224947035537408)]} 0 4
[junit4:junit4]   2> 6062 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6062 T367 C16 P43471 /update {wt=javabin&version=2} {add=[95 (1417224947038683136)]} 0 4
[junit4:junit4]   2> 6062 T354 C17 P46411 /update {wt=javabin&version=2} {add=[10090 (1417224947041828864)]} 0 0
[junit4:junit4]   2> 6064 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[80 (-1417224947043926016)]} 0 0
[junit4:junit4]   2> 6068 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6069 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[80 (-1417224947046023168)]} 0 3
[junit4:junit4]   2> 6071 T355 C17 P46411 /update {wt=javabin&version=2} {add=[96 (1417224947051266048)]} 0 0
[junit4:junit4]   2> 6079 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=3 
[junit4:junit4]   2> 6080 T366 C16 P43471 /update {wt=javabin&version=2} {add=[96 (1417224947053363200)]} 0 7
[junit4:junit4]   2> 6082 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[81 (-1417224947062800384)]} 0 0
[junit4:junit4]   2> 6087 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6087 T367 C16 P43471 /update {wt=javabin&version=2} {delete=[81 (-1417224947064897536)]} 0 3
[junit4:junit4]   2> 6091 T354 C17 P46411 /update {wt=javabin&version=2} {add=[97 (1417224947071188992)]} 0 1
[junit4:junit4]   2> 6097 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6097 T368 C16 P43471 /update {wt=javabin&version=2} {add=[97 (1417224947074334720)]} 0 4
[junit4:junit4]   2> 6100 T353 C17 P46411 /update {wt=javabin&version=2} {add=[98 (1417224947080626176)]} 0 1
[junit4:junit4]   2> 6104 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6105 T366 C16 P43471 /update {wt=javabin&version=2} {add=[98 (1417224947083771904)]} 0 4
[junit4:junit4]   2> 6106 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=40 
[junit4:junit4]   2> 6107 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10090 (1417224947043926016)]} 0 43
[junit4:junit4]   2> 6107 T355 C17 P46411 /update {wt=javabin&version=2} {add=[99 (1417224947089014784)]} 0 1
[junit4:junit4]   2> 6109 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[10072 (-1417224947091111936)]} 0 0
[junit4:junit4]   2> 6115 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 6115 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=3 
[junit4:junit4]   2> 6115 T367 C16 P43471 /update {wt=javabin&version=2} {add=[99 (1417224947091111936)]} 0 6
[junit4:junit4]   2> 6115 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[10072 (-1417224947093209088)]} 0 4
[junit4:junit4]   2> 6117 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[82 (-1417224947099500544)]} 0 0
[junit4:junit4]   2> 6121 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6121 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[82 (-1417224947101597696)]} 0 2
[junit4:junit4]   2> 6124 T355 C17 P46411 /update {wt=javabin&version=2} {add=[100 (1417224947105792000)]} 0 1
[junit4:junit4]   2> 6126 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10091 (1417224947099500545)]} 0 9
[junit4:junit4]   2> 6129 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6130 T369 C16 P43471 /update {wt=javabin&version=2} {add=[100 (1417224947109986304)]} 0 4
[junit4:junit4]   2> 6132 T352 C17 P46411 /update {wt=javabin&version=2} {delete=[83 (-1417224947115229184)]} 0 0
[junit4:junit4]   2> 6136 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6136 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[83 (-1417224947117326336)]} 0 2
[junit4:junit4]   2> 6137 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6138 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10091 (1417224947111034880)]} 0 10
[junit4:junit4]   2> 6138 T354 C17 P46411 /update {wt=javabin&version=2} {add=[101 (1417224947121520640)]} 0 0
[junit4:junit4]   2> 6139 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[10074 (-1417224947122569216)]} 0 0
[junit4:junit4]   2> 6143 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6143 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6143 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10074 (-1417224947124666368)]} 0 2
[junit4:junit4]   2> 6143 T366 C16 P43471 /update {wt=javabin&version=2} {add=[101 (1417224947123617792)]} 0 3
[junit4:junit4]   2> 6146 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10092 (1417224947128860672)]} 0 1
[junit4:junit4]   2> 6146 T352 C17 P46411 /update {wt=javabin&version=2} {add=[102 (1417224947128860673)]} 0 1
[junit4:junit4]   2> 6150 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6150 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6150 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10092 (1417224947132006400)]} 0 3
[junit4:junit4]   2> 6151 T367 C16 P43471 /update {wt=javabin&version=2} {add=[102 (1417224947132006401)]} 0 3
[junit4:junit4]   2> 6152 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[10075 (-1417224947136200704)]} 0 0
[junit4:junit4]   2> 6153 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[84 (-1417224947137249280)]} 0 1
[junit4:junit4]   2> 6156 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6156 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6157 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10075 (-1417224947139346432)]} 0 3
[junit4:junit4]   2> 6157 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[84 (-1417224947139346433)]} 0 2
[junit4:junit4]   2> 6160 T353 C17 P46411 /update {wt=javabin&version=2} {add=[10093 (1417224947143540736)]} 0 1
[junit4:junit4]   2> 6160 T352 C17 P46411 /update {wt=javabin&version=2} {add=[103 (1417224947143540737)]} 0 1
[junit4:junit4]   2> 6177 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=12 
[junit4:junit4]   2> 6178 T368 C16 P43471 /update {wt=javabin&version=2} {add=[103 (1417224947146686465)]} 0 16
[junit4:junit4]   2> 6180 T354 C17 P46411 /update {wt=javabin&version=2} {add=[104 (1417224947165560832)]} 0 0
[junit4:junit4]   2> 6181 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=17 
[junit4:junit4]   2> 6182 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10093 (1417224947146686464)]} 0 20
[junit4:junit4]   2> 6184 T355 C17 P46411 /update {wt=javabin&version=2} {delete=[10076 (-1417224947169755136)]} 0 0
[junit4:junit4]   2> 6184 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6185 T366 C16 P43471 /update {wt=javabin&version=2} {add=[104 (1417224947167657984)]} 0 3
[junit4:junit4]   2> 6187 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[86 (-1417224947172900864)]} 0 0
[junit4:junit4]   2> 6188 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6188 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[10076 (-1417224947171852288)]} 0 2
[junit4:junit4]   2> 6190 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6191 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10094 (1417224947176046592)]} 0 1
[junit4:junit4]   2> 6191 T368 C16 P43471 /update {wt=javabin&version=2} {delete=[86 (-1417224947174998016)]} 0 2
[junit4:junit4]   2> 6193 T354 C17 P46411 /update {wt=javabin&version=2} {add=[105 (1417224947179192320)]} 0 0
[junit4:junit4]   2> 6195 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6195 T367 C16 P43471 /update {wt=javabin&version=2} {add=[10094 (1417224947179192320)]} 0 3
[junit4:junit4]   2> 6198 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6199 T366 C16 P43471 /update {wt=javabin&version=2} {add=[105 (1417224947181289472)]} 0 4
[junit4:junit4]   2> 6201 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[87 (-1417224947187580928)]} 0 0
[junit4:junit4]   2> 6205 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6205 T369 C16 P43471 /update {wt=javabin&version=2} {delete=[87 (-1417224947189678080)]} 0 2
[junit4:junit4]   2> 6207 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10095 (1417224947183386624)]} 0 10
[junit4:junit4]   2> 6209 T354 C17 P46411 /update {wt=javabin&version=2} {add=[106 (1417224947193872384)]} 0 2
[junit4:junit4]   2> 6212 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6213 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10095 (1417224947195969536)]} 0 4
[junit4:junit4]   2> 6214 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6215 T367 C16 P43471 /update {wt=javabin&version=2} {add=[106 (1417224947198066688)]} 0 4
[junit4:junit4]   2> 6215 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10080 (-1417224947202260992)]} 0 0
[junit4:junit4]   2> 6217 T355 C17 P46411 /update {wt=javabin&version=2} {add=[107 (1417224947204358144)]} 0 1
[junit4:junit4]   2> 6219 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6219 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10080 (-1417224947204358144)]} 0 2
[junit4:junit4]   2> 6222 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10096 (1417224947208552448)]} 0 1
[junit4:junit4]   2> 6222 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6222 T369 C16 P43471 /update {wt=javabin&version=2} {add=[107 (1417224947206455296)]} 0 3
[junit4:junit4]   2> 6224 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[88 (-1417224947211698176)]} 0 0
[junit4:junit4]   2> 6227 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6228 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10096 (1417224947211698176)]} 0 4
[junit4:junit4]   2> 6228 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6229 T367 C16 P43471 /update {wt=javabin&version=2} {delete=[88 (-1417224947213795328)]} 0 3
[junit4:junit4]   2> 6230 T353 C17 P46411 /update {wt=javabin&version=2} {delete=[10081 (-1417224947217989632)]} 0 0
[junit4:junit4]   2> 6232 T355 C17 P46411 /update {wt=javabin&version=2} {add=[108 (1417224947220086784)]} 0 1
[junit4:junit4]   2> 6235 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6235 T366 C16 P43471 /update {wt=javabin&version=2} {delete=[10081 (-1417224947220086784)]} 0 3
[junit4:junit4]   2> 6237 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6238 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10097 (1417224947225329664)]} 0 1
[junit4:junit4]   2> 6238 T369 C16 P43471 /update {wt=javabin&version=2} {add=[108 (1417224947223232512)]} 0 4
[junit4:junit4]   2> 6240 T354 C17 P46411 /update {wt=javabin&version=2} {delete=[89 (-1417224947228475392)]} 0 0
[junit4:junit4]   2> 6244 T378 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 6245 T367 C16 P43471 /update {wt=javabin&version=2} {delete=[89 (-1417224947230572544)]} 0 3
[junit4:junit4]   2> 6248 T353 C17 P46411 /update {wt=javabin&version=2} {add=[109 (1417224947236864000)]} 0 1
[junit4:junit4]   2> 6253 T380 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=11 
[junit4:junit4]   2> 6254 T368 C16 P43471 /update {wt=javabin&version=2} {add=[10097 (1417224947228475392)]} 0 14
[junit4:junit4]   2> 6257 T355 C17 P46411 /update {wt=javabin&version=2} {add=[10098 (1417224947245252608)]} 0 1
[junit4:junit4]   2> 6265 T379 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 6266 T369 C16 P43471 /update {wt=javabin&version=2} {add=[10098 (1417224947248398336)]} 0 7
[junit4:junit4]   2> 6267 T381 C15 P55384 REQ /update {distrib.from=http://127.0.0.1:43471/solr/collection1/&wt=javabin&version=2&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 6268 T366 C16 P43471 /update {wt=javabin&version=2} {add=[109 (1417224947240009728)]} 0 17
[junit4:junit4]   2> 6269 T352 C17 P46411 /update {wt=javabin&version=2} {add=[10099 (1417224947258884096)]} 0 0
[junit4:junit4]   2> 6271 T352 C17 P46411 /upd

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

d attempting reconnect java.net.ConnectException: Connection refused
[junit4:junit4]   2> 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4:junit4]   2> 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:692)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1143)
[junit4:junit4]   2> 
[junit4:junit4]   2> 24415 T370 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:35354
[junit4:junit4]   2> 24516 T371 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 24516 T337 oaz.ZooKeeper.close Session: 0x13aafe9324c0003 closed
[junit4:junit4]   2> 24538 T337 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 24591 T337 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 55384
[junit4:junit4]   2> 24591 T337 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=20073882
[junit4:junit4]   2> 25772 T499 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:35354
[junit4:junit4]   2> 25872 T500 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 25872 T337 oaz.ZooKeeper.close Session: 0x13aafe9324c0007 closed
[junit4:junit4]   2> 25895 T337 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 26010 T337 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=EB57BF482F92D353 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pt_PT -Dtests.timezone=SystemV/PST8PDT -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   26.0s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:55384/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([EB57BF482F92D353:6AB1315058CDB36F]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:374)
[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:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 26049 T337 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {range_facet_sl=PostingsFormat(name=MockSep), timestamp=PostingsFormat(name=Memory doPackFST= true), a_t=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=MockSep), _version_=Lucene41(blocksize=128), other_tl1=Lucene41(blocksize=128), text=PostingsFormat(name=Memory doPackFST= false), rnd_b=PostingsFormat(name=MockSep), multiDefault=PostingsFormat(name=Memory doPackFST= true), range_facet_si=PostingsFormat(name=Memory doPackFST= true), range_facet_l=PostingsFormat(name=Memory doPackFST= true), a_si=Lucene41(blocksize=128), intDefault=Lucene41(blocksize=128)}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=pt_PT, timezone=SystemV/PST8PDT
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=72492912,total=268435456
[junit4:junit4]   2> NOTE: All tests run in this JVM: [PrimUtilsTest, TimeZoneUtilsTest, ZkSolrClientTest, TestMergePolicyConfig, TestSolrDeletionPolicy1, TermVectorComponentTest, BasicDistributedZk2Test, TestHashPartitioner, HighlighterTest, SimplePostToolTest, MultiTermTest, PeerSyncTest, TestCSVResponseWriter, ShowFileRequestHandlerTest, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 26.09s, 1 test, 1 error <<< FAILURES!

[...truncated 568 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1159: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:827: There were test failures: 233 suites, 965 tests, 1 error, 8 ignored (3 assumptions)

Total time: 33 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b58 -client -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message