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 # 2038 - Failure!
Date Sun, 28 Oct 2012 08:42:22 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/2038/
Java: 32bit/jdk1.8.0-ea-b58 -server -XX:+UseG1GC

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

Error Message:
Server at http://127.0.0.1:47300/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:47300/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([D25593075C200781:53B31D1F2B7F67BD]: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 8643 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 2 T1076 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-RecoveryZkTest-1351413500070
[junit4:junit4]   2> 3 T1076 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T1077 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 4 T1077 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 6 T1077 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 103 T1076 oasc.ZkTestServer.run start zk server on port:43445
[junit4:junit4]   2> 104 T1076 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:43445 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2ad81c
[junit4:junit4]   2> 104 T1082 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:43445
[junit4:junit4]   2> 104 T1076 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 105 T1078 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55249
[junit4:junit4]   2> 105 T1082 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:43445, initiating session
[junit4:junit4]   2> 106 T1078 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55249
[junit4:junit4]   2> 106 T1080 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 2158 T1080 oazsp.FileTxnLog.commit WARNING fsync-ing the write ahead log in SyncThread:0 took 2051ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide
[junit4:junit4]   2> 2160 T1080 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa68588ab0000 with negotiated timeout 10000 for client /127.0.0.1:55249
[junit4:junit4]   2> 2160 T1082 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:43445, sessionid = 0x13aa68588ab0000, negotiated timeout = 10000
[junit4:junit4]   2> 2160 T1083 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2ad81c name:ZooKeeperConnection Watcher:127.0.0.1:43445 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2160 T1076 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2161 T1076 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 2164 T1081 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa68588ab0000
[junit4:junit4]   2> 2165 T1078 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:55249 which had sessionid 0x13aa68588ab0000
[junit4:junit4]   2> 2165 T1083 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 2165 T1076 oaz.ZooKeeper.close Session: 0x13aa68588ab0000 closed
[junit4:junit4]   2> 2166 T1076 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:43445/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@15e1f63
[junit4:junit4]   2> 2168 T1084 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:43445
[junit4:junit4]   2> 2169 T1078 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55251
[junit4:junit4]   2> 2169 T1076 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2170 T1084 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:43445, initiating session
[junit4:junit4]   2> 2170 T1078 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55251
[junit4:junit4]   2> 2172 T1080 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa68588ab0001 with negotiated timeout 10000 for client /127.0.0.1:55251
[junit4:junit4]   2> 2172 T1084 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:43445, sessionid = 0x13aa68588ab0001, negotiated timeout = 10000
[junit4:junit4]   2> 2172 T1085 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15e1f63 name:ZooKeeperConnection Watcher:127.0.0.1:43445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2173 T1076 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2173 T1076 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 2175 T1076 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 2177 T1076 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 2178 T1076 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 2181 T1076 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> 2184 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2191 T1076 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> 2192 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 2194 T1076 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> 2195 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2197 T1076 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> 2198 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 2200 T1076 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> 2200 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 2202 T1076 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> 2202 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 2205 T1076 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> 2206 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 2209 T1076 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> 2210 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 2212 T1076 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> 2213 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 2215 T1076 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> 2215 T1076 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 2217 T1081 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa68588ab0001
[junit4:junit4]   2> 2218 T1078 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:55251 which had sessionid 0x13aa68588ab0001
[junit4:junit4]   2> 2218 T1076 oaz.ZooKeeper.close Session: 0x13aa68588ab0001 closed
[junit4:junit4]   2> 2218 T1085 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 2219 T1076 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2256 T1076 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:41070
[junit4:junit4]   2> 2283 T1076 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2284 T1076 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> 2284 T1076 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> 2329 T1076 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2329 T1076 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2330 T1076 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> 2330 T1076 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> 2331 T1076 oasc.CoreContainer.<init> New CoreContainer 4891435
[junit4:junit4]   2> 2331 T1076 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> 2332 T1076 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> 2353 T1076 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2365 T1076 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:43445/solr
[junit4:junit4]   2> 2366 T1076 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:43445/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1505a2e
[junit4:junit4]   2> 2367 T1095 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:43445
[junit4:junit4]   2> 2377 T1095 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:43445, initiating session
[junit4:junit4]   2> 2377 T1078 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55252
[junit4:junit4]   2> 2368 T1076 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2377 T1078 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55252
[junit4:junit4]   2> 2379 T1080 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa68588ab0002 with negotiated timeout 5000 for client /127.0.0.1:55252
[junit4:junit4]   2> 2379 T1095 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:43445, sessionid = 0x13aa68588ab0002, negotiated timeout = 5000
[junit4:junit4]   2> 2379 T1096 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1505a2e name:ZooKeeperConnection Watcher:127.0.0.1:43445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2379 T1076 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2380 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2381 T1076 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 2382 T1076 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41070_solr
[junit4:junit4]   2> 2383 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:41070_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:41070_solr
[junit4:junit4]   2> 2383 T1076 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41070_solr
[junit4:junit4]   2> 2385 T1076 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2385 T1076 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2386 T1076 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2386 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2387 T1076 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2387 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2388 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2388 T1076 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2389 T1076 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2389 T1076 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2394 T1076 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 2396 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 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> 2397 T1076 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 2398 T1076 oasc.Overseer.start Overseer (id=88566235140915202-127.0.0.1:41070_solr-n_0000000000) starting
[junit4:junit4]   2> 2399 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2399 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2400 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2401 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2402 T1098 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 2402 T1076 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 2403 T1076 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2407 T1076 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> 2407 T1076 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 2408 T1097 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 2408 T1076 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2408 T1076 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 2410 T1076 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> 2411 T1076 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> 2411 T1076 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> 2445 T1076 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 2504 T1076 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2505 T1076 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2510 T1076 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2999 T1076 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3004 T1076 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3007 T1076 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3013 T1076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3016 T1076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3019 T1076 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3021 T1076 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3023 T1076 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-1351413500070/control/data/
[junit4:junit4]   2> 3024 T1076 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@13a462c
[junit4:junit4]   2> 3025 T1076 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351413500070/control/data/index/
[junit4:junit4]   2> 3026 T1076 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351413500070/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3027 T1076 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1351413500070/control/data/index forceNew:false
[junit4:junit4]   2> 3031 T1076 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/J0/org.apache.solr.cloud.RecoveryZkTest-1351413500070/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3032 T1076 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3032 T1076 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3034 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3035 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3036 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3036 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3037 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3038 T1076 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3038 T1076 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3039 T1076 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3040 T1076 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3040 T1076 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3041 T1076 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3041 T1076 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3042 T1076 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3042 T1076 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3043 T1076 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3044 T1076 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3044 T1076 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3045 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3046 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3046 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3047 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3047 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3048 T1076 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3049 T1076 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3049 T1076 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3053 T1076 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3055 T1076 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3056 T1076 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SYNONYMS.TXT, PROTWORDS.TXT, OLD_SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML]
[junit4:junit4]   2> 3058 T1076 oass.SolrIndexSearcher.<init> Opening Searcher@12a98ec main
[junit4:junit4]   2> 3059 T1076 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3060 T1076 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3060 T1076 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3064 T1076 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3083 T1099 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12a98ec main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3085 T1076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3086 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 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> 3087 T1076 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3087 T1076 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:41070/solr shardId:control_shard
[junit4:junit4]   2> 3088 T1076 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 3091 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 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> 3091 T1076 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3092 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3411 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3412 T1097 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:41070/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:41070_solr",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "collection":"control_collection"}
[junit4:junit4]   2> 3412 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 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> 3417 T1096 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 3593 T1076 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3593 T1076 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3594 T1076 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:41070/solr/collection1/
[junit4:junit4]   2> 3594 T1076 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3595 T1076 oasc.SyncStrategy.syncToMe http://127.0.0.1:41070/solr/collection1/ has no replicas
[junit4:junit4]   2> 3595 T1076 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:41070/solr/collection1/
[junit4:junit4]   2> 3595 T1076 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 3597 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3919 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4010 T1096 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 4049 T1076 oasc.ZkController.register We are http://127.0.0.1:41070/solr/collection1/ and leader is http://127.0.0.1:41070/solr/collection1/
[junit4:junit4]   2> 4050 T1076 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:41070/solr
[junit4:junit4]   2> 4050 T1076 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4051 T1076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4052 T1076 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4053 T1076 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 4053 T1076 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4054 T1076 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4194 T1076 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4196 T1076 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:39411
[junit4:junit4]   2> 4197 T1076 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4197 T1076 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135
[junit4:junit4]   2> 4197 T1076 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135/'
[junit4:junit4]   2> 4214 T1076 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4215 T1076 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4216 T1076 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135
[junit4:junit4]   2> 4216 T1076 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135/solr.xml
[junit4:junit4]   2> 4216 T1076 oasc.CoreContainer.<init> New CoreContainer 26887856
[junit4:junit4]   2> 4217 T1076 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135/'
[junit4:junit4]   2> 4217 T1076 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135/'
[junit4:junit4]   2> 4230 T1076 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4238 T1076 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:43445/solr
[junit4:junit4]   2> 4238 T1076 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:43445/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@8aef80
[junit4:junit4]   2> 4239 T1109 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:43445
[junit4:junit4]   2> 4239 T1076 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4240 T1109 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:43445, initiating session
[junit4:junit4]   2> 4240 T1078 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55254
[junit4:junit4]   2> 4240 T1078 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55254
[junit4:junit4]   2> 4241 T1080 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa68588ab0003 with negotiated timeout 5000 for client /127.0.0.1:55254
[junit4:junit4]   2> 4241 T1109 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:43445, sessionid = 0x13aa68588ab0003, negotiated timeout = 5000
[junit4:junit4]   2> 4241 T1110 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8aef80 name:ZooKeeperConnection Watcher:127.0.0.1:43445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4242 T1076 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4242 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4243 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4244 T1076 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39411_solr
[junit4:junit4]   2> 4244 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:39411_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:39411_solr
[junit4:junit4]   2> 4245 T1076 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39411_solr
[junit4:junit4]   2> 4246 T1096 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4246 T1076 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4246 T1076 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4247 T1076 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4247 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4247 T1076 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4248 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4248 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4248 T1076 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4249 T1076 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4249 T1076 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4254 T1076 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4259 T1076 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135/collection1
[junit4:junit4]   2> 4259 T1076 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4260 T1076 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4260 T1076 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4261 T1076 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135/collection1/'
[junit4:junit4]   2> 4262 T1076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty1-1351413504135/collection1/lib/README' to classloader
[junit4:junit4]   2> 4263 T1076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty1-1351413504135/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4300 T1076 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 4339 T1076 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4340 T1076 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4343 T1076 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4512 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4513 T1097 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:41070/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:41070_solr",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "collection":"control_collection"}
[junit4:junit4]   2> 4515 T1096 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4515 T1110 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4714 T1076 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4722 T1076 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4726 T1076 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4738 T1076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4741 T1076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4746 T1076 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4747 T1076 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4749 T1076 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty1-1351413504135/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty1/
[junit4:junit4]   2> 4749 T1076 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@13a462c
[junit4:junit4]   2> 4750 T1076 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty1/index/
[junit4:junit4]   2> 4751 T1076 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4751 T1076 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty1/index forceNew:false
[junit4:junit4]   2> 4840 T1076 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/J0/org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4840 T1076 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4841 T1076 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4842 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4842 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4843 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4843 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4844 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4844 T1076 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4845 T1076 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4845 T1076 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4845 T1076 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4846 T1076 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4846 T1076 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4847 T1076 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4847 T1076 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4848 T1076 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4848 T1076 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4848 T1076 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4849 T1076 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4849 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4850 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4850 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4851 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4851 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4851 T1076 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4852 T1076 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4852 T1076 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4855 T1076 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4856 T1076 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4857 T1076 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SYNONYMS.TXT, PROTWORDS.TXT, OLD_SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML]
[junit4:junit4]   2> 4859 T1076 oass.SolrIndexSearcher.<init> Opening Searcher@489f57 main
[junit4:junit4]   2> 4860 T1076 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4860 T1076 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4861 T1076 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4864 T1076 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4885 T1111 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@489f57 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4887 T1076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5017 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5018 T1097 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:39411/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:39411_solr",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 5018 T1097 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 5020 T1096 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5020 T1110 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5388 T1076 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5389 T1076 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:39411/solr shardId:shard1
[junit4:junit4]   2> 5390 T1076 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 5394 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0003 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> 5394 T1076 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 5395 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5396 T1076 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 5397 T1076 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 5397 T1076 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:39411/solr/collection1/
[junit4:junit4]   2> 5398 T1076 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 5398 T1076 oasc.SyncStrategy.syncToMe http://127.0.0.1:39411/solr/collection1/ has no replicas
[junit4:junit4]   2> 5399 T1076 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:39411/solr/collection1/
[junit4:junit4]   2> 5399 T1076 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 5405 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5522 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5526 T1096 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5526 T1110 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5557 T1076 oasc.ZkController.register We are http://127.0.0.1:39411/solr/collection1/ and leader is http://127.0.0.1:39411/solr/collection1/
[junit4:junit4]   2> 5558 T1076 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39411/solr
[junit4:junit4]   2> 5558 T1076 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5558 T1076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5560 T1076 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5560 T1076 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5561 T1076 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5561 T1076 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5674 T1076 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5676 T1076 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:47300
[junit4:junit4]   2> 5677 T1076 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5678 T1076 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632
[junit4:junit4]   2> 5679 T1076 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632/'
[junit4:junit4]   2> 5696 T1076 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5697 T1076 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5698 T1076 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632
[junit4:junit4]   2> 5698 T1076 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632/solr.xml
[junit4:junit4]   2> 5699 T1076 oasc.CoreContainer.<init> New CoreContainer 20735090
[junit4:junit4]   2> 5699 T1076 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632/'
[junit4:junit4]   2> 5700 T1076 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632/'
[junit4:junit4]   2> 5718 T1076 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5729 T1076 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:43445/solr
[junit4:junit4]   2> 5730 T1076 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:43445/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@145f173
[junit4:junit4]   2> 5731 T1121 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:43445
[junit4:junit4]   2> 5731 T1076 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5732 T1121 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:43445, initiating session
[junit4:junit4]   2> 5732 T1078 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55255
[junit4:junit4]   2> 5732 T1078 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55255
[junit4:junit4]   2> 5733 T1080 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa68588ab0004 with negotiated timeout 5000 for client /127.0.0.1:55255
[junit4:junit4]   2> 5733 T1121 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:43445, sessionid = 0x13aa68588ab0004, negotiated timeout = 5000
[junit4:junit4]   2> 5733 T1122 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@145f173 name:ZooKeeperConnection Watcher:127.0.0.1:43445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5734 T1076 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5734 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5735 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5736 T1076 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47300_solr
[junit4:junit4]   2> 5736 T1081 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa68588ab0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:47300_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:47300_solr
[junit4:junit4]   2> 5737 T1076 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:47300_solr
[junit4:junit4]   2> 5741 T1110 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 5741 T1096 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 5742 T1076 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5742 T1076 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5743 T1076 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5743 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5744 T1076 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5744 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5745 T1076 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5745 T1076 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5746 T1076 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5746 T1076 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5752 T1076 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5757 T1076 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632/collection1
[junit4:junit4]   2> 5758 T1076 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5759 T1076 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5759 T1076 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5761 T1076 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632/collection1/'
[junit4:junit4]   2> 5761 T1076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty2-1351413505632/collection1/lib/README' to classloader
[junit4:junit4]   2> 5762 T1076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-jetty2-1351413505632/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5801 T1076 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4]   2> 5853 T1076 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5854 T1076 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5859 T1076 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6069 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6070 T1097 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:39411/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:39411_solr",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 6072 T1110 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6072 T1096 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6072 T1122 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6232 T1076 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6238 T1076 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6241 T1076 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6247 T1076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6250 T1076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6252 T1076 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6253 T1076 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6254 T1076 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-jetty2-1351413505632/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty2/
[junit4:junit4]   2> 6254 T1076 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@13a462c
[junit4:junit4]   2> 6255 T1076 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty2/index/
[junit4:junit4]   2> 6255 T1076 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6256 T1076 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty2/index forceNew:false
[junit4:junit4]   2> 6259 T1076 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/J0/org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6259 T1076 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6260 T1076 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6261 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6262 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6262 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6262 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6263 T1076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6263 T1076 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6264 T1076 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6264 T1076 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6265 T1076 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6265 T1076 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6265 T1076 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6266 T1076 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6266 T1076 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6267 T1076 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6267 T1076 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6268 T1076 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6268 T1076 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6268 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6269 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6270 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6270 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6271 T1076 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6272 T1076 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6272 T1076 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6273 T1076 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6277 T1076 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6279 T1076 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6281 T1076 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SYNONYMS.TXT, PROTWORDS.TXT, OLD_SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML]
[junit4:junit4]   2> 6285 T1076 oass.SolrIndexSearcher.<init> Opening Searcher@3ea833 main
[junit4:junit4]   2> 6286 T1076 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6287 T1076 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6287 T1076 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6292 T1076 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6315 T1123 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ea833 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6317 T1076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6575 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6575 T1097 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47300/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47300_solr",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 6577 T1110 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6577 T1096 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6578 T1122 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6818 T1076 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6819 T1076 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:47300/solr shardId:shard1
[junit4:junit4]   2> 6821 T1076 oasc.ZkController.register We are http://127.0.0.1:47300/solr/collection1/ and leader is http://127.0.0.1:39411/solr/collection1/
[junit4:junit4]   2> 6821 T1076 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:47300/solr
[junit4:junit4]   2> 6822 T1076 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 6822 T1076 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C130 name=collection1 org.apache.solr.core.SolrCore@7cf6b3 url=http://127.0.0.1:47300/solr/collection1 node=127.0.0.1:47300_solr
[junit4:junit4]   2> 6823 T1124 C130 P47300 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 6824 T1124 C130 P47300 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 6823 T1076 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6824 T1124 C130 P47300 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6824 T1076 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 6825 T1076 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6825 T1076 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6830 T1076 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:43445/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@217877
[junit4:junit4]   2> 6831 T1125 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:43445
[junit4:junit4]   2> 6831 T1076 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6831 T1078 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55256
[junit4:junit4]   2> 6832 T1125 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:43445, initiating session
[junit4:junit4]   2> 6832 T1078 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55256
[junit4:junit4]   2> 6838 T1124 C130 P47300 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:39411/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 6839 T1124 C130 P47300 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:47300/solr START replicas=[http://127.0.0.1:39411/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 6839 T1124 C130 P47300 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 6841 T1124 C130 P47300 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 6841 T1124 C130 P47300 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 6841 T1080 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa68588ab0005 with negotiated timeout 10000 for client /127.0.0.1:55256
[junit4:junit4]   2> 6842 T1124 C130 P47300 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6842 T1125 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:43445, sessionid = 0x13aa68588ab0005, negotiated timeout = 10000
[junit4:junit4]   2> 6845 T1126 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@217877 name:ZooKeeperConnection Watcher:127.0.0.1:43445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6845 T1076 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6849 T1076 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6850 T1076 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 6850 T1076 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 6853 T1076 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C131 name=collection1 org.apache.solr.core.SolrCore@ebfe01 url=http://127.0.0.1:39411/solr/collection1 node=127.0.0.1:39411_solr
[junit4:junit4]   2> 6864 T1106 C131 P39411 REQ /get {wt=javabin&getVersions=100&qt=/get&version=2&distrib=false} status=0 QTime=15 
[junit4:junit4]   2> ASYNC  NEW_CORE C132 name=collection1 org.apache.solr.core.SolrCore@8a277e url=http://127.0.0.1:41070/solr/collection1 node=127.0.0.1:41070_solr
[junit4:junit4]   2> 6870 T1091 C132 P41070 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/J0/org.apache.solr.cloud.RecoveryZkTest-1351413500070/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6870 T1091 C132 P41070 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6872 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10001 (1417059769449971712)]} 0 3
[junit4:junit4]   2> 6872 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[1 (1417059769448923136)]} 0 4
[junit4:junit4]   2> 6873 T1129 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:43445/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@13b1b11
[junit4:junit4]   2> 6874 T1130 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:43445
[junit4:junit4]   2> 6874 T1129 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6874 T1130 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:43445, initiating session
[junit4:junit4]   2> 6874 T1078 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55263
[junit4:junit4]   2> 6875 T1078 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55263
[junit4:junit4]   2> 6876 T1080 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa68588ab0006 with negotiated timeout 10000 for client /127.0.0.1:55263
[junit4:junit4]   2> 6876 T1130 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:43445, sessionid = 0x13aa68588ab0006, negotiated timeout = 10000
[junit4:junit4]   2> 6876 T1131 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13b1b11 name:ZooKeeperConnection Watcher:127.0.0.1:43445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6876 T1129 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6877 T1129 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6891 T1105 C131 P39411 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/J0/org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6893 T1105 C131 P39411 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6895 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10001 (1417059769474088960)]} 0 3
[junit4:junit4]   2> 6895 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[1 (1417059769471991808)]} 0 5
[junit4:junit4]   2> 6898 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10002 (1417059769480380416)]} 0 0
[junit4:junit4]   2> 6899 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[2 (1417059769480380417)]} 0 1
[junit4:junit4]   2> 6901 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10002 (1417059769482477568)]} 0 1
[junit4:junit4]   2> 6901 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[2 (1417059769483526144)]} 0 1
[junit4:junit4]   2> 6904 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[3 (1417059769485623296)]} 0 1
[junit4:junit4]   2> 6903 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10003 (1417059769485623297)]} 0 0
[junit4:junit4]   2> 6906 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[3 (1417059769488769025)]} 0 0
[junit4:junit4]   2> 6906 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10003 (1417059769488769024)]} 0 0
[junit4:junit4]   2> 6909 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[4 (1417059769491914752)]} 0 0
[junit4:junit4]   2> 6909 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10004 (1417059769491914753)]} 0 0
[junit4:junit4]   2> 6912 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[4 (1417059769494011904)]} 0 1
[junit4:junit4]   2> 6912 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10004 (1417059769494011905)]} 0 1
[junit4:junit4]   2> 6914 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[10004 (-1417059769497157633)]} 0 0
[junit4:junit4]   2> 6914 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[4 (-1417059769497157632)]} 0 0
[junit4:junit4]   2> 6916 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10004 (-1417059769499254784)]} 0 0
[junit4:junit4]   2> 6917 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[4 (-1417059769500303360)]} 0 0
[junit4:junit4]   2> 6918 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10005 (1417059769501351936)]} 0 0
[junit4:junit4]   2> 6921 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10005 (1417059769503449088)]} 0 1
[junit4:junit4]   2> 6930 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10006 (1417059769511837696)]} 0 2
[junit4:junit4]   2> 6935 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10006 (1417059769517080576)]} 0 2
[junit4:junit4]   2> 6936 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[5 (1417059769503449088)]} 0 16
[junit4:junit4]   2> 6937 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10007 (1417059769521274880)]} 0 0
[junit4:junit4]   2> 6938 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[5 (1417059769522323456)]} 0 0
[junit4:junit4]   2> 6939 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10007 (1417059769523372032)]} 0 0
[junit4:junit4]   2> 6940 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[6 (1417059769524420608)]} 0 0
[junit4:junit4]   2> 6942 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10008 (1417059769526517760)]} 0 0
[junit4:junit4]   2> 6942 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[6 (1417059769526517760)]} 0 0
[junit4:junit4]   2> 6945 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[7 (1417059769528614912)]} 0 1
[junit4:junit4]   2> 6951 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[7 (1417059769534906368)]} 0 1
[junit4:junit4]   2> 6953 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[8 (1417059769538052096)]} 0 0
[junit4:junit4]   2> 6954 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10008 (1417059769528614912)]} 0 10
[junit4:junit4]   2> 6955 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[8 (1417059769540149248)]} 0 0
[junit4:junit4]   2> 6957 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10009 (1417059769542246400)]} 0 0
[junit4:junit4]   2> 6958 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[9 (1417059769543294976)]} 0 1
[junit4:junit4]   2> 6959 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10009 (1417059769544343552)]} 0 0
[junit4:junit4]   2> 6961 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10010 (1417059769546440704)]} 0 0
[junit4:junit4]   2> 6969 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10010 (1417059769554829313)]} 0 0
[junit4:junit4]   2> 6969 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[9 (1417059769554829312)]} 0 0
[junit4:junit4]   2> 6973 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10008 (-1417059769559023616)]} 0 0
[junit4:junit4]   2> 6976 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10008 (-1417059769562169344)]} 0 0
[junit4:junit4]   2> 6979 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10011 (1417059769564266496)]} 0 1
[junit4:junit4]   2> 6981 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10011 (1417059769567412224)]} 0 0
[junit4:junit4]   2> 6984 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10012 (1417059769569509376)]} 0 1
[junit4:junit4]   2> 6986 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10012 (1417059769572655104)]} 0 0
[junit4:junit4]   2> 6989 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10013 (1417059769574752256)]} 0 1
[junit4:junit4]   2> 6991 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10013 (1417059769577897984)]} 0 1
[junit4:junit4]   2> 6993 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10014 (1417059769579995136)]} 0 0
[junit4:junit4]   2> 6996 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10014 (1417059769582092288)]} 0 1
[junit4:junit4]   2> 6998 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10015 (1417059769585238016)]} 0 0
[junit4:junit4]   2> 7000 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10015 (1417059769587335168)]} 0 0
[junit4:junit4]   2> 7003 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10010 (-1417059769590480896)]} 0 1
[junit4:junit4]   2> 7004 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10 (1417059769557975040)]} 0 32
[junit4:junit4]   2> 7005 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10010 (-1417059769592578048)]} 0 0
[junit4:junit4]   2> 7007 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10 (1417059769594675200)]} 0 0
[junit4:junit4]   2> 7007 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10016 (1417059769594675200)]} 0 0
[junit4:junit4]   2> 7010 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10016 (1417059769597820928)]} 0 0
[junit4:junit4]   2> 7011 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[8 (-1417059769598869504)]} 0 0
[junit4:junit4]   2> 7012 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10011 (-1417059769599918080)]} 0 0
[junit4:junit4]   2> 7013 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[8 (-1417059769600966656)]} 0 0
[junit4:junit4]   2> 7015 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10011 (-1417059769603063808)]} 0 0
[junit4:junit4]   2> 7016 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[11 (1417059769603063808)]} 0 1
[junit4:junit4]   2> 7018 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10017 (1417059769606209536)]} 0 1
[junit4:junit4]   2> 7023 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10017 (1417059769610403840)]} 0 1
[junit4:junit4]   2> 7025 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10012 (-1417059769613549568)]} 0 0
[junit4:junit4]   2> 7027 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10012 (-1417059769615646720)]} 0 0
[junit4:junit4]   2> 7030 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10018 (1417059769618792448)]} 0 0
[junit4:junit4]   2> 7037 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10018 (1417059769626132480)]} 0 0
[junit4:junit4]   2> 7040 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[11 (1417059769607258112)]} 0 21
[junit4:junit4]   2> 7040 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10013 (-1417059769629278208)]} 0 0
[junit4:junit4]   2> 7048 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10013 (-1417059769637666816)]} 0 0
[junit4:junit4]   2> 7049 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[12 (1417059769631375360)]} 0 7
[junit4:junit4]   2> 7058 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[12 (1417059769640812544)]} 0 7
[junit4:junit4]   2> 7051 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10019 (1417059769639763968)]} 0 1
[junit4:junit4]   2> 7060 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[13 (1417059769650249728)]} 0 0
[junit4:junit4]   2> 7061 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10019 (1417059769650249728)]} 0 1
[junit4:junit4]   2> 7063 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[13 (1417059769652346880)]} 0 1
[junit4:junit4]   2> 7064 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10015 (-1417059769654444032)]} 0 0
[junit4:junit4]   2> 7066 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[14 (1417059769655492608)]} 0 1
[junit4:junit4]   2> 7066 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10015 (-1417059769656541184)]} 0 0
[junit4:junit4]   2> 7068 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[14 (1417059769657589760)]} 0 1
[junit4:junit4]   2> 7069 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10020 (1417059769658638336)]} 0 1
[junit4:junit4]   2> 7070 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[15 (1417059769660735488)]} 0 1
[junit4:junit4]   2> 7071 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10020 (1417059769660735488)]} 0 1
[junit4:junit4]   2> 7072 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[15 (1417059769661784064)]} 0 1
[junit4:junit4]   2> 7073 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10016 (-1417059769662832640)]} 0 1
[junit4:junit4]   2> 7074 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[10 (-1417059769663881216)]} 0 1
[junit4:junit4]   2> 7075 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10016 (-1417059769664929792)]} 0 1
[junit4:junit4]   2> 7075 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10 (-1417059769665978368)]} 0 0
[junit4:junit4]   2> 7077 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10021 (1417059769667026944)]} 0 1
[junit4:junit4]   2> 7077 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[16 (1417059769668075520)]} 0 0
[junit4:junit4]   2> 7079 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10021 (1417059769670172672)]} 0 0
[junit4:junit4]   2> 7080 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7079 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[16 (1417059769670172673)]} 0 0
[junit4:junit4]   2> 7080 T1097 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47300/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47300_solr",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 7081 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10018 (-1417059769672269824)]} 0 0
[junit4:junit4]   2> 7081 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[11 (-1417059769672269825)]} 0 0
[junit4:junit4]   2> 7082 T1096 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7082 T1126 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7082 T1122 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7083 T1131 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7084 T1110 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2>  C130_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, base_url=http://127.0.0.1:47300/solr, state=recovering, node_name=127.0.0.1:47300_solr, core=collection1, collection=collection1}
[junit4:junit4]   2> 7092 T1120 C130 P47300 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/J0/org.apache.solr.cloud.RecoveryZkTest-1351413500070/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7093 T1120 C130 P47300 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7094 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 7094 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[11 (-1417059769676464129)]} 0 9
[junit4:junit4]   2> 7094 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 7095 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10018 (-1417059769676464128)]} 0 10
[junit4:junit4]   2> 7097 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[17 (1417059769689047040)]} 0 0
[junit4:junit4]   2> 7098 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10022 (1417059769689047041)]} 0 1
[junit4:junit4]   2> 7103 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 7103 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10022 (1417059769692192768)]} 0 4
[junit4:junit4]   2> 7104 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7104 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[17 (1417059769692192769)]} 0 4
[junit4:junit4]   2> 7105 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10020 (-1417059769697435648)]} 0 0
[junit4:junit4]   2> 7106 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[12 (-1417059769698484224)]} 0 0
[junit4:junit4]   2> 7109 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7109 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10020 (-1417059769699532800)]} 0 2
[junit4:junit4]   2> 7110 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7110 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[12 (-1417059769700581376)]} 0 2
[junit4:junit4]   2> 7113 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[18 (1417059769704775680)]} 0 1
[junit4:junit4]   2> 7117 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7118 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[18 (1417059769707921408)]} 0 3
[junit4:junit4]   2> 7128 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[13 (-1417059769721552896)]} 0 0
[junit4:junit4]   2> 7129 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10023 (1417059769703727104)]} 0 18
[junit4:junit4]   2> 7132 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7133 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[13 (-1417059769723650048)]} 0 3
[junit4:junit4]   2> 7134 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7134 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10023 (1417059769724698624)]} 0 3
[junit4:junit4]   2> 7135 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[19 (1417059769728892928)]} 0 0
[junit4:junit4]   2> 7136 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10024 (1417059769729941504)]} 0 0
[junit4:junit4]   2> 7139 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7140 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[19 (1417059769730990080)]} 0 3
[junit4:junit4]   2> 7140 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7141 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10024 (1417059769732038656)]} 0 3
[junit4:junit4]   2> 7142 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[15 (-1417059769736232960)]} 0 0
[junit4:junit4]   2> 7143 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10025 (1417059769737281536)]} 0 0
[junit4:junit4]   2> 7145 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7146 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[15 (-1417059769738330112)]} 0 2
[junit4:junit4]   2> 7147 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7147 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10025 (1417059769739378688)]} 0 2
[junit4:junit4]   2> 7149 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[20 (1417059769742524416)]} 0 1
[junit4:junit4]   2> 7149 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10026 (1417059769743572992)]} 0 0
[junit4:junit4]   2> 7153 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7153 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[20 (1417059769745670144)]} 0 2
[junit4:junit4]   2> 7155 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[16 (-1417059769749864448)]} 0 0
[junit4:junit4]   2> 7159 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7160 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[16 (-1417059769751961600)]} 0 3
[junit4:junit4]   2> 7162 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7162 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[21 (1417059769757204480)]} 0 1
[junit4:junit4]   2> 7163 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10026 (1417059769745670145)]} 0 12
[junit4:junit4]   2> 7165 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10027 (1417059769759301632)]} 0 1
[junit4:junit4]   2> 7174 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=7 
[junit4:junit4]   2> 7175 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=4 
[junit4:junit4]   2> 7175 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[21 (1417059769759301632)]} 0 11
[junit4:junit4]   2> 7175 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10027 (1417059769762447360)]} 0 8
[junit4:junit4]   2> 7178 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[18 (-1417059769773981696)]} 0 0
[junit4:junit4]   2> 7187 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 7188 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[18 (-1417059769776078848)]} 0 8
[junit4:junit4]   2> 7221 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[22 (1417059769819070464)]} 0 0
[junit4:junit4]   2> 7229 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7230 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[22 (1417059769825361920)]} 0 3
[junit4:junit4]   2> 7231 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10028 (1417059769821167616)]} 0 9
[junit4:junit4]   2> 7232 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[20 (-1417059769830604800)]} 0 0
[junit4:junit4]   2> 7236 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7236 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10028 (1417059769832701952)]} 0 3
[junit4:junit4]   2> 7237 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7238 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[20 (-1417059769832701953)]} 0 4
[junit4:junit4]   2> 7239 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10029 (1417059769836896256)]} 0 1
[junit4:junit4]   2> 7240 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[23 (1417059769837944832)]} 0 1
[junit4:junit4]   2> 7243 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7244 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7244 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10029 (1417059769840041984)]} 0 3
[junit4:junit4]   2> 7244 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[23 (1417059769841090560)]} 0 2
[junit4:junit4]   2> 7246 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10030 (1417059769845284864)]} 0 0
[junit4:junit4]   2> 7247 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[24 (1417059769846333440)]} 0 1
[junit4:junit4]   2> 7250 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7250 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10030 (1417059769847382016)]} 0 2
[junit4:junit4]   2> 7254 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10023 (-1417059769853673472)]} 0 2
[junit4:junit4]   2> 7258 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7258 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[24 (1417059769848430592)]} 0 10
[junit4:junit4]   2> 7259 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7260 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10023 (-1417059769857867776)]} 0 5
[junit4:junit4]   2> 7261 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[25 (1417059769859964928)]} 0 1
[junit4:junit4]   2> 7262 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10031 (1417059769861013504)]} 0 1
[junit4:junit4]   2> 7264 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7265 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[25 (1417059769862062080)]} 0 3
[junit4:junit4]   2> 7266 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7266 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10031 (1417059769863110656)]} 0 3
[junit4:junit4]   2> 7267 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[26 (1417059769867304960)]} 0 0
[junit4:junit4]   2> 7268 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10032 (1417059769868353536)]} 0 0
[junit4:junit4]   2> 7277 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7278 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[26 (1417059769869402112)]} 0 9
[junit4:junit4]   2> 7279 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=7 
[junit4:junit4]   2> 7280 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10032 (1417059769870450688)]} 0 10
[junit4:junit4]   2> 7281 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[27 (1417059769881985024)]} 0 0
[junit4:junit4]   2> 7281 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10025 (-1417059769881985025)]} 0 0
[junit4:junit4]   2> 7285 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7285 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7286 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10025 (-1417059769885130752)]} 0 3
[junit4:junit4]   2> 7286 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[27 (1417059769884082176)]} 0 3
[junit4:junit4]   2> 7288 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[28 (1417059769888276481)]} 0 1
[junit4:junit4]   2> 7293 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10033 (1417059769888276480)]} 0 6
[junit4:junit4]   2> 7298 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7299 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10033 (1417059769896665088)]} 0 4
[junit4:junit4]   2> 7301 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10026 (-1417059769902956544)]} 0 0
[junit4:junit4]   2> 7305 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7305 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10026 (-1417059769905053696)]} 0 2
[junit4:junit4]   2> 7308 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10034 (1417059769909248000)]} 0 1
[junit4:junit4]   2> 7309 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7309 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[28 (1417059769901907968)]} 0 10
[junit4:junit4]   2> 7312 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[29 (1417059769913442304)]} 0 1
[junit4:junit4]   2> 7312 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7312 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10034 (1417059769912393728)]} 0 2
[junit4:junit4]   2> 7315 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10035 (1417059769916588032)]} 0 1
[junit4:junit4]   2> 7317 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7317 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[29 (1417059769916588032)]} 0 3
[junit4:junit4]   2> 7319 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7319 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10035 (1417059769919733760)]} 0 3
[junit4:junit4]   2> 7319 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[30 (1417059769921830912)]} 0 0
[junit4:junit4]   2> 7321 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10036 (1417059769923928064)]} 0 0
[junit4:junit4]   2> 7324 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7325 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[30 (1417059769924976640)]} 0 3
[junit4:junit4]   2> 7325 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7326 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10036 (1417059769926025216)]} 0 3
[junit4:junit4]   2> 7327 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[23 (-1417059769930219520)]} 0 0
[junit4:junit4]   2> 7328 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10037 (1417059769931268096)]} 0 0
[junit4:junit4]   2> 7331 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7334 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[23 (-1417059769932316672)]} 0 5
[junit4:junit4]   2> 7336 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 7337 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[31 (1417059769939656704)]} 0 1
[junit4:junit4]   2> 7337 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10037 (1417059769933365248)]} 0 7
[junit4:junit4]   2> 7339 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10027 (-1417059769942802432)]} 0 0
[junit4:junit4]   2> 7342 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=2 
[junit4:junit4]   2> 7342 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[31 (1417059769942802432)]} 0 3
[junit4:junit4]   2> 7343 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7343 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10027 (-1417059769944899584)]} 0 2
[junit4:junit4]   2> 7344 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[32 (1417059769948045312)]} 0 0
[junit4:junit4]   2> 7349 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7349 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[32 (1417059769951191040)]} 0 2
[junit4:junit4]   2> 7351 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[25 (-1417059769955385344)]} 0 0
[junit4:junit4]   2> 7355 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10038 (1417059769949093888)]} 0 10
[junit4:junit4]   2> 7355 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7356 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[25 (-1417059769957482496)]} 0 3
[junit4:junit4]   2> 7358 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[33 (1417059769961676800)]} 0 1
[junit4:junit4]   2> 7361 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7361 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10038 (1417059769961676800)]} 0 4
[junit4:junit4]   2> 7362 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7363 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10028 (-1417059769967968256)]} 0 0
[junit4:junit4]   2> 7363 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[33 (1417059769964822528)]} 0 3
[junit4:junit4]   2> 7366 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[26 (-1417059769971113984)]} 0 0
[junit4:junit4]   2> 7366 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7368 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10028 (-1417059769970065408)]} 0 3
[junit4:junit4]   2> 7370 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7370 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10039 (1417059769975308288)]} 0 0
[junit4:junit4]   2> 7370 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[26 (-1417059769973211136)]} 0 2
[junit4:junit4]   2> 7372 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[34 (1417059769977405440)]} 0 0
[junit4:junit4]   2> 7374 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7375 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10039 (1417059769977405440)]} 0 3
[junit4:junit4]   2> 7376 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7377 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[34 (1417059769979502592)]} 0 3
[junit4:junit4]   2> 7380 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[35 (1417059769985794048)]} 0 1
[junit4:junit4]   2> 7384 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7385 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[35 (1417059769987891200)]} 0 3
[junit4:junit4]   2> 7387 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[36 (1417059769993134080)]} 0 0
[junit4:junit4]   2> 7391 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7392 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[36 (1417059769995231232)]} 0 3
[junit4:junit4]   2> 7394 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[37 (1417059770000474112)]} 0 0
[junit4:junit4]   2> 7399 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7399 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[37 (1417059770002571264)]} 0 3
[junit4:junit4]   2> 7421 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[27 (-1417059770027737088)]} 0 20
[junit4:junit4]   2> 7421 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10030 (-1417059770028785664)]} 0 44
[junit4:junit4]   2> 7425 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7425 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7425 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10030 (-1417059770030882817)]} 0 2
[junit4:junit4]   2> 7426 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[27 (-1417059770030882816)]} 0 3
[junit4:junit4]   2> 7427 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10040 (1417059770035077120)]} 0 0
[junit4:junit4]   2> 7428 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[38 (1417059770036125696)]} 0 0
[junit4:junit4]   2> 7435 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7436 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[38 (1417059770038222848)]} 0 6
[junit4:junit4]   2> 7437 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 7438 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[28 (-1417059770046611456)]} 0 0
[junit4:junit4]   2> 7438 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10040 (1417059770037174272)]} 0 9
[junit4:junit4]   2> 7440 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10031 (-1417059770048708608)]} 0 0
[junit4:junit4]   2> 7442 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7443 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[28 (-1417059770048708608)]} 0 3
[junit4:junit4]   2> 7444 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7445 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[39 (1417059770052902912)]} 0 1
[junit4:junit4]   2> 7445 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10031 (-1417059770051854336)]} 0 2
[junit4:junit4]   2> 7466 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7467 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[39 (1417059770055000064)]} 0 21
[junit4:junit4]   2> 7469 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[30 (-1417059770079117312)]} 0 0
[junit4:junit4]   2> 7472 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7473 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[30 (-1417059770081214464)]} 0 2
[junit4:junit4]   2> 7476 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[40 (1417059770085408768)]} 0 1
[junit4:junit4]   2> 7505 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10041 (1417059770056048640)]} 0 58
[junit4:junit4]   2> 7510 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7511 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10041 (1417059770120011776)]} 0 4
[junit4:junit4]   2> 7513 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10032 (-1417059770125254656)]} 0 0
[junit4:junit4]   2> 7516 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7517 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7517 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[40 (1417059770088554496)]} 0 39
[junit4:junit4]   2> 7517 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10032 (-1417059770127351808)]} 0 2
[junit4:junit4]   2> 7519 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[31 (-1417059770131546112)]} 0 0
[junit4:junit4]   2> 7520 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10042 (1417059770132594688)]} 0 1
[junit4:junit4]   2> 7524 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7524 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7525 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[31 (-1417059770134691841)]} 0 3
[junit4:junit4]   2> 7525 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10042 (1417059770134691840)]} 0 3
[junit4:junit4]   2> 7527 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[41 (1417059770139934720)]} 0 1
[junit4:junit4]   2> 7527 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10033 (-1417059770139934721)]} 0 0
[junit4:junit4]   2> 7531 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7531 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10033 (-1417059770142031873)]} 0 2
[junit4:junit4]   2> 7532 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7532 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[41 (1417059770142031872)]} 0 3
[junit4:junit4]   2> 7533 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10043 (1417059770146226176)]} 0 0
[junit4:junit4]   2> 7534 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[32 (-1417059770147274752)]} 0 0
[junit4:junit4]   2> 7542 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7543 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7543 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[32 (-1417059770149371904)]} 0 7
[junit4:junit4]   2> 7544 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10043 (1417059770154614784)]} 0 9
[junit4:junit4]   2> 7546 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[42 (1417059770158809088)]} 0 1
[junit4:junit4]   2> 7546 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10044 (1417059770159857664)]} 0 0
[junit4:junit4]   2> 7550 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7551 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7552 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[42 (1417059770161954816)]} 0 4
[junit4:junit4]   2> 7552 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10044 (1417059770163003392)]} 0 4
[junit4:junit4]   2> 7554 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[33 (-1417059770168246272)]} 0 0
[junit4:junit4]   2> 7555 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10035 (-1417059770169294848)]} 0 0
[junit4:junit4]   2> 7558 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7558 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7559 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10035 (-1417059770170343425)]} 0 3
[junit4:junit4]   2> 7559 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[33 (-1417059770170343424)]} 0 3
[junit4:junit4]   2> 7561 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10045 (1417059770174537728)]} 0 1
[junit4:junit4]   2> 7561 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[43 (1417059770175586304)]} 0 0
[junit4:junit4]   2> 7566 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7566 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10045 (1417059770178732032)]} 0 3
[junit4:junit4]   2> 7569 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10046 (1417059770182926336)]} 0 0
[junit4:junit4]   2> 7571 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 7572 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[43 (1417059770177683456)]} 0 9
[junit4:junit4]   2> 7572 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7573 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10046 (1417059770186072064)]} 0 3
[junit4:junit4]   2> 7575 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10047 (1417059770190266368)]} 0 0
[junit4:junit4]   2> 7579 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7580 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10047 (1417059770192363520)]} 0 3
[junit4:junit4]   2> 7583 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10048 (1417059770197606400)]} 0 1
[junit4:junit4]   2> 7587 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7587 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10048 (1417059770200752128)]} 0 2
[junit4:junit4]   2> 7589 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10049 (1417059770204946432)]} 0 0
[junit4:junit4]   2> 7592 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[44 (1417059770188169216)]} 0 19
[junit4:junit4]   2> 7593 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7593 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10049 (1417059770207043584)]} 0 2
[junit4:junit4]   2> 7595 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10038 (-1417059770211237888)]} 0 0
[junit4:junit4]   2> 7596 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7596 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[44 (1417059770210189312)]} 0 2
[junit4:junit4]   2> 7598 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[35 (-1417059770214383616)]} 0 0
[junit4:junit4]   2> 7599 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7599 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10038 (-1417059770213335040)]} 0 2
[junit4:junit4]   2> 7601 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10050 (1417059770217529344)]} 0 0
[junit4:junit4]   2> 7601 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7602 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[35 (-1417059770216480768)]} 0 2
[junit4:junit4]   2> 7604 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[45 (1417059770219626496)]} 0 1
[junit4:junit4]   2> 7605 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7605 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10050 (1417059770219626496)]} 0 2
[junit4:junit4]   2> 7607 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7607 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10039 (-1417059770223820800)]} 0 0
[junit4:junit4]   2> 7608 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[45 (1417059770221723648)]} 0 3
[junit4:junit4]   2> 7610 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[46 (1417059770226966528)]} 0 0
[junit4:junit4]   2> 7611 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7611 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10039 (-1417059770225917952)]} 0 2
[junit4:junit4]   2> 7613 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10051 (1417059770230112256)]} 0 0
[junit4:junit4]   2> 7613 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7614 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[46 (1417059770229063680)]} 0 2
[junit4:junit4]   2> 7618 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[47 (1417059770234306560)]} 0 1
[junit4:junit4]   2> 7624 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7627 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 7627 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[47 (1417059770237452288)]} 0 7
[junit4:junit4]   2> 7627 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10051 (1417059770232209408)]} 0 12
[junit4:junit4]   2> 7629 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10043 (-1417059770246889473)]} 0 0
[junit4:junit4]   2> 7629 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[48 (1417059770246889472)]} 0 0
[junit4:junit4]   2> 7633 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7633 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10043 (-1417059770248986624)]} 0 2
[junit4:junit4]   2> 7634 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7634 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[48 (1417059770248986625)]} 0 3
[junit4:junit4]   2> 7637 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[49 (1417059770254229504)]} 0 1
[junit4:junit4]   2> 7641 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10052 (1417059770253180928)]} 0 6
[junit4:junit4]   2> 7644 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7644 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[49 (1417059770260520960)]} 0 2
[junit4:junit4]   2> 7645 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7646 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10052 (1417059770261569536)]} 0 3
[junit4:junit4]   2> 7646 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[38 (-1417059770264715264)]} 0 0
[junit4:junit4]   2> 7648 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10044 (-1417059770266812416)]} 0 0
[junit4:junit4]   2> 7650 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7650 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[38 (-1417059770266812416)]} 0 2
[junit4:junit4]   2> 7651 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7652 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10044 (-1417059770268909568)]} 0 2
[junit4:junit4]   2> 7652 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[50 (1417059770271006720)]} 0 0
[junit4:junit4]   2> 7653 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10053 (1417059770272055296)]} 0 0
[junit4:junit4]   2> 7656 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7656 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[50 (1417059770273103872)]} 0 2
[junit4:junit4]   2> 7657 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7657 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10053 (1417059770274152448)]} 0 2
[junit4:junit4]   2> 7658 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[39 (-1417059770277298176)]} 0 0
[junit4:junit4]   2> 7659 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10045 (-1417059770278346752)]} 0 0
[junit4:junit4]   2> 7661 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7662 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[39 (-1417059770279395328)]} 0 2
[junit4:junit4]   2> 7662 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7662 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10045 (-1417059770280443904)]} 0 1
[junit4:junit4]   2> 7663 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[51 (1417059770282541056)]} 0 0
[junit4:junit4]   2> 7664 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10054 (1417059770283589632)]} 0 0
[junit4:junit4]   2> 7667 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7667 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[51 (1417059770284638208)]} 0 2
[junit4:junit4]   2> 7668 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7668 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10054 (1417059770285686784)]} 0 2
[junit4:junit4]   2> 7669 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[43 (-1417059770288832512)]} 0 0
[junit4:junit4]   2> 7670 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10047 (-1417059770289881088)]} 0 0
[junit4:junit4]   2> 7672 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7673 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[43 (-1417059770290929664)]} 0 2
[junit4:junit4]   2> 7673 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7673 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10047 (-1417059770291978240)]} 0 1
[junit4:junit4]   2> 7675 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[52 (1417059770294075392)]} 0 1
[junit4:junit4]   2> 7675 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10055 (1417059770295123968)]} 0 0
[junit4:junit4]   2> 7678 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7678 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[52 (1417059770296172544)]} 0 2
[junit4:junit4]   2> 7680 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[44 (-1417059770300366848)]} 0 0
[junit4:junit4]   2> 7683 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7684 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[44 (-1417059770302464000)]} 0 2
[junit4:junit4]   2> 7684 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 7685 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10055 (1417059770297221120)]} 0 8
[junit4:junit4]   2> 7686 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[53 (1417059770306658304)]} 0 1
[junit4:junit4]   2> 7687 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10056 (1417059770306658305)]} 0 1
[junit4:junit4]   2> 7690 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7690 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[53 (1417059770307706880)]} 0 3
[junit4:junit4]   2> 7693 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[45 (-1417059770313998336)]} 0 0
[junit4:junit4]   2> 7695 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7696 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10056 (1417059770309804032)]} 0 8
[junit4:junit4]   2> 7696 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7697 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[45 (-1417059770316095488)]} 0 2
[junit4:junit4]   2> 7699 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[54 (1417059770320289792)]} 0 1
[junit4:junit4]   2> 7703 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10057 (1417059770318192640)]} 0 6
[junit4:junit4]   2> 7705 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7706 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[54 (1417059770324484096)]} 0 3
[junit4:junit4]   2> 7706 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7706 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10057 (1417059770325532672)]} 0 2
[junit4:junit4]   2> 7708 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[47 (-1417059770329726976)]} 0 0
[junit4:junit4]   2> 7708 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[10048 (-1417059770329726977)]} 0 0
[junit4:junit4]   2> 7711 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7711 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7711 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[47 (-1417059770331824128)]} 0 2
[junit4:junit4]   2> 7712 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10048 (-1417059770331824129)]} 0 2
[junit4:junit4]   2> 7713 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[55 (1417059770334969856)]} 0 0
[junit4:junit4]   2> 7714 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10058 (1417059770334969857)]} 0 1
[junit4:junit4]   2> 7717 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7718 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[55 (1417059770337067008)]} 0 3
[junit4:junit4]   2> 7720 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[56 (1417059770341261312)]} 0 1
[junit4:junit4]   2> 7722 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7722 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10058 (1417059770337067009)]} 0 7
[junit4:junit4]   2> 7724 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7724 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[56 (1417059770344407040)]} 0 2
[junit4:junit4]   2> 7724 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10059 (1417059770346504192)]} 0 0
[junit4:junit4]   2> 7726 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[57 (1417059770348601344)]} 0 0
[junit4:junit4]   2> 7728 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7728 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10059 (1417059770348601344)]} 0 2
[junit4:junit4]   2> 7730 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7730 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10050 (-1417059770352795648)]} 0 0
[junit4:junit4]   2> 7730 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[57 (1417059770350698496)]} 0 2
[junit4:junit4]   2> 7732 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[48 (-1417059770354892800)]} 0 0
[junit4:junit4]   2> 7733 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7734 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10050 (-1417059770354892800)]} 0 2
[junit4:junit4]   2> 7735 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7736 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10060 (1417059770358038528)]} 0 1
[junit4:junit4]   2> 7736 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[48 (-1417059770356989952)]} 0 2
[junit4:junit4]   2> 7738 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[58 (1417059770361184256)]} 0 0
[junit4:junit4]   2> 7742 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7742 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[58 (1417059770362232832)]} 0 3
[junit4:junit4]   2> 7744 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[59 (1417059770367475712)]} 0 0
[junit4:junit4]   2> 7749 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7749 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[59 (1417059770369572864)]} 0 3
[junit4:junit4]   2> 7752 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[50 (-1417059770374815744)]} 0 1
[junit4:junit4]   2> 7755 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7756 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[50 (-1417059770377961472)]} 0 2
[junit4:junit4]   2> 7774 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[60 (1417059770382155776)]} 0 16
[junit4:junit4]   2> 7774 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=35 
[junit4:junit4]   2> 7775 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10060 (1417059770360135680)]} 0 38
[junit4:junit4]   2> 7777 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10052 (-1417059770401030144)]} 0 1
[junit4:junit4]   2> 7778 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7778 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[60 (1417059770401030144)]} 0 2
[junit4:junit4]   2> 7780 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7780 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[52 (-1417059770405224448)]} 0 0
[junit4:junit4]   2> 7781 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10052 (-1417059770403127296)]} 0 3
[junit4:junit4]   2> 7783 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10061 (1417059770407321600)]} 0 1
[junit4:junit4]   2> 7784 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7784 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[52 (-1417059770407321600)]} 0 2
[junit4:junit4]   2> 7786 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[61 (1417059770411515904)]} 0 0
[junit4:junit4]   2> 7787 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7788 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10061 (1417059770410467328)]} 0 3
[junit4:junit4]   2> 7790 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10053 (-1417059770414661632)]} 0 1
[junit4:junit4]   2> 7793 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7793 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10053 (-1417059770416758784)]} 0 2
[junit4:junit4]   2> 7795 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7795 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10062 (1417059770420953088)]} 0 0
[junit4:junit4]   2> 7795 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[61 (1417059770413613056)]} 0 7
[junit4:junit4]   2> 7798 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[53 (-1417059770423050240)]} 0 0
[junit4:junit4]   2> 7800 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7801 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10062 (1417059770423050240)]} 0 4
[junit4:junit4]   2> 7802 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7803 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[53 (-1417059770426195968)]} 0 3
[junit4:junit4]   2> 7804 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[10054 (-1417059770430390272)]} 0 0
[junit4:junit4]   2> 7805 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[62 (1417059770431438848)]} 0 0
[junit4:junit4]   2> 7808 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7809 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10054 (-1417059770432487424)]} 0 3
[junit4:junit4]   2> 7810 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7811 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10063 (1417059770436681728)]} 0 1
[junit4:junit4]   2> 7811 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[62 (1417059770434584576)]} 0 4
[junit4:junit4]   2> 7813 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[54 (-1417059770439827456)]} 0 0
[junit4:junit4]   2> 7815 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7815 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10063 (1417059770439827456)]} 0 2
[junit4:junit4]   2> 7817 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7817 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[54 (-1417059770441924608)]} 0 2
[junit4:junit4]   2> 7817 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10055 (-1417059770444021760)]} 0 0
[junit4:junit4]   2> 7820 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[63 (1417059770446118912)]} 0 1
[junit4:junit4]   2> 7821 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7821 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10055 (-1417059770446118912)]} 0 2
[junit4:junit4]   2> 7824 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7824 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[63 (1417059770449264640)]} 0 2
[junit4:junit4]   2> 7824 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10064 (1417059770451361792)]} 0 0
[junit4:junit4]   2> 7826 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[55 (-1417059770453458944)]} 0 0
[junit4:junit4]   2> 7830 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7830 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[55 (-1417059770455556096)]} 0 2
[junit4:junit4]   2> 7833 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[64 (1417059770459750400)]} 0 1
[junit4:junit4]   2> 7834 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 7834 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10064 (1417059770453458944)]} 0 8
[junit4:junit4]   2> 7836 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10065 (1417059770463944704)]} 0 0
[junit4:junit4]   2> 7838 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7838 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[64 (1417059770462896128)]} 0 3
[junit4:junit4]   2> 7841 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7841 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10065 (1417059770467090432)]} 0 2
[junit4:junit4]   2> 7843 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10056 (-1417059770471284736)]} 0 0
[junit4:junit4]   2> 7846 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[65 (1417059770468139008)]} 0 6
[junit4:junit4]   2> 7847 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7847 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10056 (-1417059770473381888)]} 0 2
[junit4:junit4]   2> 7850 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10066 (1417059770477576192)]} 0 1
[junit4:junit4]   2> 7850 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7851 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[65 (1417059770476527616)]} 0 3
[junit4:junit4]   2> 7853 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[56 (-1417059770481770496)]} 0 0
[junit4:junit4]   2> 7854 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7854 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10066 (1417059770480721920)]} 0 2
[junit4:junit4]   2> 7856 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10057 (-1417059770484916224)]} 0 0
[junit4:junit4]   2> 7856 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7856 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[56 (-1417059770483867648)]} 0 1
[junit4:junit4]   2> 7858 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[66 (1417059770487013376)]} 0 0
[junit4:junit4]   2> 7859 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7859 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10057 (-1417059770487013376)]} 0 1
[junit4:junit4]   2> 7861 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10067 (1417059770490159104)]} 0 0
[junit4:junit4]   2> 7862 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7863 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[66 (1417059770489110528)]} 0 3
[junit4:junit4]   2> 7865 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7865 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10067 (1417059770492256256)]} 0 2
[junit4:junit4]   2> 7865 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[57 (-1417059770494353408)]} 0 0
[junit4:junit4]   2> 7867 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10064 (-1417059770496450560)]} 0 0
[junit4:junit4]   2> 7869 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7869 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[57 (-1417059770496450560)]} 0 2
[junit4:junit4]   2> 7870 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7870 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10064 (-1417059770497499136)]} 0 2
[junit4:junit4]   2> 7871 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[67 (1417059770500644864)]} 0 0
[junit4:junit4]   2> 7872 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10068 (1417059770501693440)]} 0 0
[junit4:junit4]   2> 7875 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7875 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7876 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10068 (1417059770503790592)]} 0 2
[junit4:junit4]   2> 7876 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[67 (1417059770502742016)]} 0 3
[junit4:junit4]   2> 7878 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10066 (-1417059770507984896)]} 0 1
[junit4:junit4]   2> 7878 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[64 (-1417059770507984897)]} 0 0
[junit4:junit4]   2> 7881 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7882 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[10066 (-1417059770509033472)]} 0 3
[junit4:junit4]   2> 7882 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7882 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[64 (-1417059770510082048)]} 0 2
[junit4:junit4]   2> 7884 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10069 (1417059770513227776)]} 0 1
[junit4:junit4]   2> 7884 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[68 (1417059770514276352)]} 0 0
[junit4:junit4]   2> 7889 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7889 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[68 (1417059770516373505)]} 0 3
[junit4:junit4]   2> 7891 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[66 (-1417059770521616384)]} 0 0
[junit4:junit4]   2> 7895 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7895 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[66 (-1417059770523713536)]} 0 2
[junit4:junit4]   2> 7897 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[69 (1417059770527907840)]} 0 0
[junit4:junit4]   2> 7901 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 7901 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10069 (1417059770516373504)]} 0 15
[junit4:junit4]   2> 7902 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7903 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[69 (1417059770530004992)]} 0 4
[junit4:junit4]   2> 7904 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10067 (-1417059770534199296)]} 0 1
[junit4:junit4]   2> 7905 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[67 (-1417059770536296448)]} 0 0
[junit4:junit4]   2> 7907 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7907 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10067 (-1417059770536296448)]} 0 2
[junit4:junit4]   2> 7908 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7909 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[67 (-1417059770537345024)]} 0 3
[junit4:junit4]   2> 7910 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10070 (1417059770541539328)]} 0 0
[junit4:junit4]   2> 7914 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7914 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10070 (1417059770543636480)]} 0 2
[junit4:junit4]   2> 7917 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10071 (1417059770547830784)]} 0 1
[junit4:junit4]   2> 7921 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[70 (1417059770542587904)]} 0 10
[junit4:junit4]   2> 7921 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7922 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10071 (1417059770550976512)]} 0 3
[junit4:junit4]   2> 7924 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10070 (-1417059770556219392)]} 0 0
[junit4:junit4]   2> 7925 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7926 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[70 (1417059770555170816)]} 0 3
[junit4:junit4]   2> 7928 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7928 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[71 (1417059770560413696)]} 0 0
[junit4:junit4]   2> 7929 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[10070 (-1417059770558316544)]} 0 3
[junit4:junit4]   2> 7931 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10072 (1417059770563559424)]} 0 0
[junit4:junit4]   2> 7933 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7933 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[71 (1417059770562510848)]} 0 3
[junit4:junit4]   2> 7935 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[70 (-1417059770567753728)]} 0 0
[junit4:junit4]   2> 7936 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7937 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10072 (1417059770565656576)]} 0 4
[junit4:junit4]   2> 7940 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10073 (1417059770571948032)]} 0 1
[junit4:junit4]   2> 7941 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7942 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[70 (-1417059770570899456)]} 0 5
[junit4:junit4]   2> 7944 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[72 (1417059770577190912)]} 0 0
[junit4:junit4]   2> 7948 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7948 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[10073 (1417059770578239488)]} 0 3
[junit4:junit4]   2> 7949 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7949 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[72 (1417059770580336640)]} 0 3
[junit4:junit4]   2> 7951 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10074 (1417059770583482368)]} 0 1
[junit4:junit4]   2> 7951 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[73 (1417059770584530944)]} 0 0
[junit4:junit4]   2> 7955 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7956 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10074 (1417059770585579520)]} 0 4
[junit4:junit4]   2> 7958 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10072 (-1417059770591870976)]} 0 1
[junit4:junit4]   2> 7961 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7962 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[10072 (-1417059770593968128)]} 0 2
[junit4:junit4]   2> 7964 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10075 (1417059770598162432)]} 0 0
[junit4:junit4]   2> 7969 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 7969 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10075 (1417059770600259584)]} 0 3
[junit4:junit4]   2> 7994 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10076 (1417059770629619712)]} 0 0
[junit4:junit4]   2> 7996 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=35 
[junit4:junit4]   2> 7997 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[73 (1417059770586628096)]} 0 44
[junit4:junit4]   2> 7999 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 7999 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10076 (1417059770632765440)]} 0 2
[junit4:junit4]   2> 8002 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10077 (1417059770636959744)]} 0 1
[junit4:junit4]   2> 8046 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[74 (1417059770634862592)]} 0 47
[junit4:junit4]   2> 8051 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8051 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8052 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[10077 (1417059770640105472)]} 0 48
[junit4:junit4]   2> 8052 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[74 (1417059770686242816)]} 0 4
[junit4:junit4]   2> 8055 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10078 (1417059770692534272)]} 0 1
[junit4:junit4]   2> 8055 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[72 (-1417059770693582848)]} 0 0
[junit4:junit4]   2> 8059 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8059 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10078 (1417059770695680000)]} 0 2
[junit4:junit4]   2> 8060 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8060 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[72 (-1417059770695680001)]} 0 3
[junit4:junit4]   2> 8061 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10073 (-1417059770699874304)]} 0 0
[junit4:junit4]   2> 8063 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[75 (1417059770701971456)]} 0 0
[junit4:junit4]   2> 8065 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8065 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10073 (-1417059770701971456)]} 0 2
[junit4:junit4]   2> 8068 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10079 (1417059770706165760)]} 0 1
[junit4:junit4]   2> 8069 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8069 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[75 (1417059770704068608)]} 0 4
[junit4:junit4]   2> 8076 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8079 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10079 (1417059770712457216)]} 0 6
[junit4:junit4]   2> 8080 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[76 (1417059770718748672)]} 0 1
[junit4:junit4]   2> 8081 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10080 (1417059770720845824)]} 0 0
[junit4:junit4]   2> 8085 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8085 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[76 (1417059770720845824)]} 0 4
[junit4:junit4]   2> 8086 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8086 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10080 (1417059770722942976)]} 0 3
[junit4:junit4]   2> 8087 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[77 (1417059770727137280)]} 0 0
[junit4:junit4]   2> 8088 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[10081 (1417059770728185856)]} 0 0
[junit4:junit4]   2> 8098 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8099 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10081 (1417059770730283008)]} 0 9
[junit4:junit4]   2> 8099 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=8 
[junit4:junit4]   2> 8100 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[77 (1417059770729234432)]} 0 11
[junit4:junit4]   2> 8101 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[10075 (-1417059770741817344)]} 0 0
[junit4:junit4]   2> 8102 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[78 (1417059770741817345)]} 0 1
[junit4:junit4]   2> 8104 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8104 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[10075 (-1417059770743914496)]} 0 2
[junit4:junit4]   2> 8105 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8106 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[78 (1417059770743914497)]} 0 3
[junit4:junit4]   2> 8107 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[73 (-1417059770748108800)]} 0 0
[junit4:junit4]   2> 8111 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8111 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[73 (-1417059770750205952)]} 0 2
[junit4:junit4]   2> 8110 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10082 (1417059770747060224)]} 0 4
[junit4:junit4]   2> 8114 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[79 (1417059770754400256)]} 0 1
[junit4:junit4]   2> 8117 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8117 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10082 (1417059770756497408)]} 0 2
[junit4:junit4]   2> 8118 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8119 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[79 (1417059770757545984)]} 0 3
[junit4:junit4]   2> 8119 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10076 (-1417059770760691712)]} 0 0
[junit4:junit4]   2> 8121 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[80 (1417059770761740288)]} 0 1
[junit4:junit4]   2> 8122 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8123 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[10076 (-1417059770762788864)]} 0 3
[junit4:junit4]   2> 8125 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10083 (1417059770766983168)]} 0 0
[junit4:junit4]   2> 8129 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8129 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8129 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[80 (1417059770763837440)]} 0 7
[junit4:junit4]   2> 8129 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10083 (1417059770769080320)]} 0 2
[junit4:junit4]   2> 8131 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[81 (1417059770773274624)]} 0 0
[junit4:junit4]   2> 8131 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10084 (1417059770773274625)]} 0 0
[junit4:junit4]   2> 8135 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8135 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8135 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[81 (1417059770775371776)]} 0 2
[junit4:junit4]   2> 8135 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10084 (1417059770775371777)]} 0 2
[junit4:junit4]   2> 8137 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[75 (-1417059770779566080)]} 0 0
[junit4:junit4]   2> 8137 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[10079 (-1417059770779566081)]} 0 0
[junit4:junit4]   2> 8140 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8140 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8141 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[75 (-1417059770781663232)]} 0 2
[junit4:junit4]   2> 8141 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10079 (-1417059770781663233)]} 0 2
[junit4:junit4]   2> 8143 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[82 (1417059770784808960)]} 0 1
[junit4:junit4]   2> 8143 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10085 (1417059770784808961)]} 0 1
[junit4:junit4]   2> 8146 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8147 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[82 (1417059770786906112)]} 0 3
[junit4:junit4]   2> 8149 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[76 (-1417059770792148992)]} 0 1
[junit4:junit4]   2> 8152 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8152 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=6 
[junit4:junit4]   2> 8152 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[76 (-1417059770793197568)]} 0 2
[junit4:junit4]   2> 8153 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[10085 (1417059770787954688)]} 0 9
[junit4:junit4]   2> 8154 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[10080 (-1417059770797391873)]} 0 0
[junit4:junit4]   2> 8155 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[83 (1417059770797391872)]} 0 1
[junit4:junit4]   2> 8158 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8158 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10080 (-1417059770799489024)]} 0 2
[junit4:junit4]   2> 8159 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8160 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[83 (1417059770799489025)]} 0 4
[junit4:junit4]   2> 8160 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10086 (1417059770803683328)]} 0 0
[junit4:junit4]   2> 8166 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[84 (1417059770804731904)]} 0 5
[junit4:junit4]   2> 8168 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8168 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10086 (1417059770809974784)]} 0 2
[junit4:junit4]   2> 8169 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8170 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[84 (1417059770811023360)]} 0 3
[junit4:junit4]   2> 8170 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[10081 (-1417059770814169088)]} 0 0
[junit4:junit4]   2> 8171 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[79 (-1417059770815217664)]} 0 0
[junit4:junit4]   2> 8173 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8174 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10081 (-1417059770816266240)]} 0 2
[junit4:junit4]   2> 8175 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8175 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[79 (-1417059770817314816)]} 0 2
[junit4:junit4]   2> 8176 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10087 (1417059770820460544)]} 0 0
[junit4:junit4]   2> 8177 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[85 (1417059770821509120)]} 0 0
[junit4:junit4]   2> 8180 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8181 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10087 (1417059770822557696)]} 0 3
[junit4:junit4]   2> 8181 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8181 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[85 (1417059770823606272)]} 0 2
[junit4:junit4]   2> 8183 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10088 (1417059770827800576)]} 0 0
[junit4:junit4]   2> 8184 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[80 (-1417059770827800577)]} 0 1
[junit4:junit4]   2> 8187 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8187 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10088 (1417059770829897728)]} 0 2
[junit4:junit4]   2> 8187 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8188 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[80 (-1417059770829897729)]} 0 3
[junit4:junit4]   2> 8190 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10089 (1417059770834092032)]} 0 1
[junit4:junit4]   2> 8190 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[86 (1417059770835140608)]} 0 0
[junit4:junit4]   2> 8194 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8194 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10089 (1417059770837237760)]} 0 2
[junit4:junit4]   2> 8194 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8195 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[86 (1417059770837237761)]} 0 3
[junit4:junit4]   2> 8196 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[10082 (-1417059770841432064)]} 0 0
[junit4:junit4]   2> 8197 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[81 (-1417059770842480640)]} 0 0
[junit4:junit4]   2> 8200 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8200 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10082 (-1417059770843529216)]} 0 2
[junit4:junit4]   2> 8201 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8202 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[10090 (1417059770847723520)]} 0 0
[junit4:junit4]   2> 8203 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[81 (-1417059770845626368)]} 0 3
[junit4:junit4]   2> 8205 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[87 (1417059770850869248)]} 0 0
[junit4:junit4]   2> 8208 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8209 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[10090 (1417059770850869248)]} 0 4
[junit4:junit4]   2> 8211 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10091 (1417059770857160704)]} 0 0
[junit4:junit4]   2> 8214 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=5 
[junit4:junit4]   2> 8215 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[87 (1417059770852966400)]} 0 8
[junit4:junit4]   2> 8217 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[88 (1417059770863452160)]} 0 0
[junit4:junit4]   2> 8222 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8222 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8222 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10091 (1417059770859257856)]} 0 9
[junit4:junit4]   2> 8222 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[88 (1417059770866597888)]} 0 3
[junit4:junit4]   2> 8224 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10084 (-1417059770870792192)]} 0 0
[junit4:junit4]   2> 8229 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8229 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10084 (-1417059770872889344)]} 0 3
[junit4:junit4]   2> 8230 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[89 (1417059770870792193)]} 0 6
[junit4:junit4]   2> 8232 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10092 (1417059770878132224)]} 0 1
[junit4:junit4]   2> 8234 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8235 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[89 (1417059770879180800)]} 0 3
[junit4:junit4]   2> 8236 T1091 C132 P41070 /update {wt=javabin&version=2} {delete=[82 (-1417059770883375104)]} 0 0
[junit4:junit4]   2> 8237 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8237 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10092 (1417059770881277952)]} 0 3
[junit4:junit4]   2> 8239 T1093 C132 P41070 /update {wt=javabin&version=2} {delete=[10085 (-1417059770886520832)]} 0 0
[junit4:junit4]   2> 8240 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8240 T1108 C131 P39411 /update {wt=javabin&version=2} {delete=[82 (-1417059770885472256)]} 0 2
[junit4:junit4]   2> 8242 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8242 T1107 C131 P39411 /update {wt=javabin&version=2} {delete=[10085 (-1417059770888617984)]} 0 2
[junit4:junit4]   2> 8243 T1092 C132 P41070 /update {wt=javabin&version=2} {add=[90 (1417059770889666560)]} 0 1
[junit4:junit4]   2> 8244 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10093 (1417059770891763712)]} 0 0
[junit4:junit4]   2> 8248 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8249 T1105 C131 P39411 /update {wt=javabin&version=2} {add=[10093 (1417059770893860864)]} 0 3
[junit4:junit4]   2> 8251 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8251 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10094 (1417059770899103744)]} 0 0
[junit4:junit4]   2> 8251 T1106 C131 P39411 /update {wt=javabin&version=2} {add=[90 (1417059770891763712)]} 0 7
[junit4:junit4]   2> 8253 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[91 (1417059770901200896)]} 0 0
[junit4:junit4]   2> 8255 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=1 
[junit4:junit4]   2> 8255 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[10094 (1417059770901200896)]} 0 2
[junit4:junit4]   2> 8257 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10086 (-1417059770905395200)]} 0 0
[junit4:junit4]   2> 8257 T1119 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8257 T1107 C131 P39411 /update {wt=javabin&version=2} {add=[91 (1417059770903298048)]} 0 2
[junit4:junit4]   2> 8259 T1094 C132 P41070 /update {wt=javabin&version=2} {delete=[84 (-1417059770907492352)]} 0 0
[junit4:junit4]   2> 8260 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8260 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10086 (-1417059770907492352)]} 0 2
[junit4:junit4]   2> 8262 T1091 C132 P41070 /update {wt=javabin&version=2} {add=[10095 (1417059770910638080)]} 0 0
[junit4:junit4]   2> 8262 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8263 T1106 C131 P39411 /update {wt=javabin&version=2} {delete=[84 (-1417059770909589504)]} 0 2
[junit4:junit4]   2> 8265 T1093 C132 P41070 /update {wt=javabin&version=2} {add=[92 (1417059770912735232)]} 0 1
[junit4:junit4]   2> 8266 T1120 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8266 T1108 C131 P39411 /update {wt=javabin&version=2} {add=[10095 (1417059770912735232)]} 0 2
[junit4:junit4]   2> 8268 T1092 C132 P41070 /update {wt=javabin&version=2} {delete=[10088 (-1417059770916929536)]} 0 0
[junit4:junit4]   2> 8275 T1117 C130 P47300 REQ /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:39411/solr/collection1/&update.distrib=FROMLEADER} status=0 QTime=0 
[junit4:junit4]   2> 8276 T1105 C131 P39411 /update {wt=javabin&version=2} {delete=[10088 (-1417059770922172416)]} 0 3
[junit4:junit4]   2> 8279 T1094 C132 P41070 /update {wt=javabin&version=2} {add=[10096 (1417059770927415296)]} 0 1
[junit4:junit4]   2> 8308 T1118 C130 P47300 REQ /update {wt=javabin&version=2&distrib.fro

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

45
[junit4:junit4]   2> 30024 T1244 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 30024 T1076 oaz.ZooKeeper.close Session: 0x13aa68588ab0007 closed
[junit4:junit4]   2> 30048 T1076 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 30147 T1076 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=D25593075C200781 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ru_RU -Dtests.timezone=America/Argentina/Catamarca -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   30.2s J0 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:47300/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([D25593075C200781:53B31D1F2B7F67BD]: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> 30165 T1076 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {a_t=PostingsFormat(name=Lucene41WithOrds), timestamp=PostingsFormat(name=Lucene41WithOrds), _version_=PostingsFormat(name=Lucene41WithOrds), intDefault=PostingsFormat(name=Lucene41WithOrds), other_tl1=PostingsFormat(name=Lucene41WithOrds), a_si=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=Memory doPackFST= true), range_facet_l=PostingsFormat(name=Lucene41WithOrds), multiDefault=PostingsFormat(name=Memory doPackFST= false), rnd_b=PostingsFormat(name=Memory doPackFST= true), range_facet_sl=Pulsing41(freqCutoff=18 minBlockSize=69 maxBlockSize=235), text=Pulsing41(freqCutoff=18 minBlockSize=69 maxBlockSize=235), range_facet_si=PostingsFormat(name=Memory doPackFST= false)}, sim=DefaultSimilarity, locale=ru_RU, timezone=America/Argentina/Catamarca
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=160043720,total=536870912
[junit4:junit4]   2> NOTE: All tests run in this JVM: [UpdateRequestProcessorFactoryTest, SimpleFacetsTest, IndexBasedSpellCheckerTest, ScriptEngineTest, TestXIncludeConfig, TestCollationKeyRangeQueries, FastVectorHighlighterTest, TestQuerySenderNoQuery, SolrCoreCheckLockOnStartupTest, PreAnalyzedFieldTest, TestQueryTypes, TestNumberUtils, RequestHandlersTest, TestCollationField, TestSolrIndexConfig, PathHierarchyTokenizerFactoryTest, JSONWriterTest, TestIBSimilarityFactory, StatelessScriptUpdateProcessorFactoryTest, CoreAdminHandlerTest, TermsComponentTest, TestSystemIdResolver, DirectUpdateHandlerOptimizeTest, DateMathParserTest, SolrRequestParserTest, SampleTest, TestPseudoReturnFields, BadComponentTest, TestIndexingPerformance, MBeansHandlerTest, SolrPluginUtilsTest, UniqFieldsUpdateProcessorFactoryTest, TestFastOutputStream, CacheHeaderTest, NoCacheHeaderTest, SpellCheckComponentTest, DefaultValueUpdateProcessorTest, TestFaceting, TestCoreContainer, FieldAnalysisRequestHandlerTest, AlternateDirectoryTest, TestCharFilters, SpatialFilterTest, TestBadConfig, DistanceFunctionTest, BinaryUpdateRequestHandlerTest, JsonLoaderTest, SearchHandlerTest, AnalysisAfterCoreReloadTest, TestSuggestSpellingConverter, MoreLikeThisHandlerTest, TestSearchPerf, SolrCmdDistributorTest, NumericFieldsTest, TestSolrQueryParser, BasicDistributedZkTest, RecoveryZkTest]
[junit4:junit4] Completed on J0 in 30.18s, 1 test, 1 error <<< FAILURES!

[...truncated 360 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, 7 ignored (2 assumptions)

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



Mime
View raw message