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.7.0_07) - Build # 1259 - Failure!
Date Fri, 21 Sep 2012 16:41:03 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/1259/
Java: 32bit/jdk1.7.0_07 -server -XX:+UseParallelGC

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

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




Build Log:
[...truncated 7681 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 3 T835 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1348245238843
[junit4:junit4]   2> 4 T835 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 5 T836 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 5 T836 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 6 T836 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 104 T835 oasc.ZkTestServer.run start zk server on port:32944
[junit4:junit4]   2> 105 T835 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32944 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@e1cb28
[junit4:junit4]   2> 106 T841 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32944
[junit4:junit4]   2> 106 T835 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T841 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:32944, initiating session
[junit4:junit4]   2> 106 T837 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39127
[junit4:junit4]   2> 107 T837 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39127
[junit4:junit4]   2> 107 T839 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 119 T839 oazs.NIOServerCnxn.finishSessionInit Established session 0x139e9adb43d0000 with negotiated timeout 10000 for client /127.0.0.1:39127
[junit4:junit4]   2> 119 T841 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:32944, sessionid = 0x139e9adb43d0000, negotiated timeout = 10000
[junit4:junit4]   2> 120 T842 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e1cb28 name:ZooKeeperConnection Watcher:127.0.0.1:32944 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 120 T835 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 120 T835 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 122 T840 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139e9adb43d0000
[junit4:junit4]   2> 122 T842 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 122 T835 oaz.ZooKeeper.close Session: 0x139e9adb43d0000 closed
[junit4:junit4]   2> 122 T837 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39127 which had sessionid 0x139e9adb43d0000
[junit4:junit4]   2> 122 T835 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32944/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4ba679
[junit4:junit4]   2> 123 T843 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32944
[junit4:junit4]   2> 123 T835 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 123 T843 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:32944, initiating session
[junit4:junit4]   2> 123 T837 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39128
[junit4:junit4]   2> 124 T837 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39128
[junit4:junit4]   2> 124 T839 oazs.NIOServerCnxn.finishSessionInit Established session 0x139e9adb43d0001 with negotiated timeout 10000 for client /127.0.0.1:39128
[junit4:junit4]   2> 124 T843 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:32944, sessionid = 0x139e9adb43d0001, negotiated timeout = 10000
[junit4:junit4]   2> 125 T844 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4ba679 name:ZooKeeperConnection Watcher:127.0.0.1:32944/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 125 T835 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 125 T835 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 126 T835 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 128 T835 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 129 T835 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 130 T835 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> 130 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 132 T835 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> 133 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 136 T835 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> 137 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 141 T835 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> 141 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 143 T835 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> 143 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 144 T835 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> 144 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 146 T835 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> 146 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 147 T835 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> 148 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 149 T835 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> 150 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 151 T835 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> 151 T835 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 152 T840 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139e9adb43d0001
[junit4:junit4]   2> 153 T844 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 153 T835 oaz.ZooKeeper.close Session: 0x139e9adb43d0001 closed
[junit4:junit4]   2> 153 T837 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:39128 which had sessionid 0x139e9adb43d0001
[junit4:junit4]   2> 153 T835 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 156 T835 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:53645
[junit4:junit4]   2> 157 T835 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 157 T835 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> 158 T835 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> 168 T835 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 169 T835 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 169 T835 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> 169 T835 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> 170 T835 oasc.CoreContainer.<init> New CoreContainer 12066664
[junit4:junit4]   2> 170 T835 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> 170 T835 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> 186 T835 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 193 T835 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:32944/solr
[junit4:junit4]   2> 194 T835 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32944/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@9d0492
[junit4:junit4]   2> 194 T854 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32944
[junit4:junit4]   2> 194 T835 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 195 T854 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:32944, initiating session
[junit4:junit4]   2> 195 T837 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39131
[junit4:junit4]   2> 195 T837 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39131
[junit4:junit4]   2> 196 T839 oazs.NIOServerCnxn.finishSessionInit Established session 0x139e9adb43d0002 with negotiated timeout 5000 for client /127.0.0.1:39131
[junit4:junit4]   2> 196 T854 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:32944, sessionid = 0x139e9adb43d0002, negotiated timeout = 5000
[junit4:junit4]   2> 197 T855 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9d0492 name:ZooKeeperConnection Watcher:127.0.0.1:32944/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 197 T835 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 198 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 198 T835 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 199 T835 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53645_solr
[junit4:junit4]   2> 200 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:53645_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:53645_solr
[junit4:junit4]   2> 200 T835 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53645_solr
[junit4:junit4]   2> 202 T835 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 202 T835 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 203 T835 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 203 T835 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 204 T835 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 204 T835 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 205 T835 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 205 T835 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 205 T835 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 206 T835 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 212 T835 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 216 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 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> 218 T835 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 220 T835 oasc.Overseer.start Overseer (id=88358599972945922-127.0.0.1:53645_solr-n_0000000000) starting
[junit4:junit4]   2> 220 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 221 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 223 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 223 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 224 T857 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 224 T835 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 226 T835 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 228 T856 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 233 T835 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> 234 T835 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 235 T835 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 235 T835 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 237 T835 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> 238 T835 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> 239 T835 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> 293 T835 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 335 T835 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 336 T835 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 339 T835 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 640 T835 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 646 T835 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 648 T835 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 659 T835 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 661 T835 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 663 T835 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 664 T835 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 665 T835 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-1348245238843/control/data/
[junit4:junit4]   2> 665 T835 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9cef7
[junit4:junit4]   2> 666 T835 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1348245238843/control/data/index/
[junit4:junit4]   2> 666 T835 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1348245238843/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 667 T835 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1348245238843/control/data/index forceNew:false
[junit4:junit4]   2> 670 T835 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1348245238843/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 670 T835 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 670 T835 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 672 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 672 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 673 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 673 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 674 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 674 T835 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 674 T835 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 675 T835 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 675 T835 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 676 T835 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 676 T835 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 677 T835 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 677 T835 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 677 T835 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 678 T835 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 678 T835 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 679 T835 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 679 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 679 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 680 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 680 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 681 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 681 T835 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 682 T835 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 682 T835 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 683 T835 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 685 T835 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 685 T835 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 687 T835 oass.SolrIndexSearcher.<init> Opening Searcher@131b250 main
[junit4:junit4]   2> 688 T835 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 688 T835 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 689 T835 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 692 T835 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 714 T858 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@131b250 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 720 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 723 T835 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 724 T835 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:53645/solr shardId:control_shard
[junit4:junit4]   2> 725 T835 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 730 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:delete cxid:0x61 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> 731 T856 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 731 T835 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 732 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 732 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 738 T855 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1234 T835 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1235 T835 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1236 T835 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53645/solr/collection1/
[junit4:junit4]   2> 1236 T835 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1237 T835 oasc.SyncStrategy.syncToMe http://127.0.0.1:53645/solr/collection1/ has no replicas
[junit4:junit4]   2> 1238 T835 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53645/solr/collection1/
[junit4:junit4]   2> 1238 T835 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1243 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1743 T856 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1748 T855 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1796 T835 oasc.ZkController.register We are http://127.0.0.1:53645/solr/collection1/ and leader is http://127.0.0.1:53645/solr/collection1/
[junit4:junit4]   2> 1796 T835 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53645/solr
[junit4:junit4]   2> 1797 T835 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1799 T835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1800 T835 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1801 T835 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1801 T835 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1860 T835 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1862 T835 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:41846
[junit4:junit4]   2> 1862 T835 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1863 T835 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647
[junit4:junit4]   2> 1863 T835 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647/'
[junit4:junit4]   2> 1874 T835 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1874 T835 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1875 T835 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647
[junit4:junit4]   2> 1875 T835 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647/solr.xml
[junit4:junit4]   2> 1875 T835 oasc.CoreContainer.<init> New CoreContainer 2441851
[junit4:junit4]   2> 1876 T835 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647/'
[junit4:junit4]   2> 1876 T835 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647/'
[junit4:junit4]   2> 1887 T835 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1893 T835 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:32944/solr
[junit4:junit4]   2> 1894 T835 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32944/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@23e777
[junit4:junit4]   2> 1894 T868 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32944
[junit4:junit4]   2> 1894 T835 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1895 T868 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:32944, initiating session
[junit4:junit4]   2> 1895 T837 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39132
[junit4:junit4]   2> 1895 T837 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39132
[junit4:junit4]   2> 1896 T839 oazs.NIOServerCnxn.finishSessionInit Established session 0x139e9adb43d0003 with negotiated timeout 5000 for client /127.0.0.1:39132
[junit4:junit4]   2> 1896 T868 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:32944, sessionid = 0x139e9adb43d0003, negotiated timeout = 5000
[junit4:junit4]   2> 1897 T869 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@23e777 name:ZooKeeperConnection Watcher:127.0.0.1:32944/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1897 T835 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1897 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1898 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1899 T835 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41846_solr
[junit4:junit4]   2> 1900 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:41846_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:41846_solr
[junit4:junit4]   2> 1900 T835 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41846_solr
[junit4:junit4]   2> 1901 T855 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 1901 T835 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1902 T835 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1902 T835 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1902 T835 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1903 T835 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1903 T835 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1903 T835 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1903 T835 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1904 T835 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1904 T835 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1911 T835 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1917 T835 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647/collection1
[junit4:junit4]   2> 1917 T835 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 1918 T835 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1919 T835 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 1920 T835 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647/collection1/'
[junit4:junit4]   2> 1922 T835 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty1-1348245240647/collection1/lib/README' to classloader
[junit4:junit4]   2> 1922 T835 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty1-1348245240647/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1957 T835 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 1989 T835 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1990 T835 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1993 T835 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2251 T856 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2253 T855 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2253 T869 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2263 T835 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2268 T835 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2271 T835 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2279 T835 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2282 T835 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2285 T835 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2285 T835 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2286 T835 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty1-1348245240647/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty1/
[junit4:junit4]   2> 2287 T835 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9cef7
[junit4:junit4]   2> 2287 T835 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty1/index/
[junit4:junit4]   2> 2288 T835 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2288 T835 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty1/index forceNew:false
[junit4:junit4]   2> 2292 T835 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2292 T835 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2293 T835 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2295 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2296 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2296 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2297 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2298 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2299 T835 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2299 T835 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2300 T835 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2301 T835 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2302 T835 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2302 T835 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2303 T835 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2303 T835 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2303 T835 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2304 T835 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2304 T835 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2305 T835 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2305 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2306 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2306 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2307 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2307 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2307 T835 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2308 T835 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2308 T835 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2310 T835 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2311 T835 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2312 T835 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 2314 T835 oass.SolrIndexSearcher.<init> Opening Searcher@c90633 main
[junit4:junit4]   2> 2314 T835 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2315 T835 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2315 T835 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2319 T835 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2338 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c90633 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2756 T856 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2759 T855 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2759 T869 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2843 T835 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 2844 T835 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:41846/solr shardId:shard1
[junit4:junit4]   2> 2846 T835 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 2851 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0003 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> 2851 T835 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2852 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2853 T835 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2853 T835 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2853 T835 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:41846/solr/collection1/
[junit4:junit4]   2> 2854 T835 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2854 T835 oasc.SyncStrategy.syncToMe http://127.0.0.1:41846/solr/collection1/ has no replicas
[junit4:junit4]   2> 2854 T835 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:41846/solr/collection1/
[junit4:junit4]   2> 2855 T835 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 2857 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3262 T856 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3267 T855 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3267 T869 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3309 T835 oasc.ZkController.register We are http://127.0.0.1:41846/solr/collection1/ and leader is http://127.0.0.1:41846/solr/collection1/
[junit4:junit4]   2> 3309 T835 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:41846/solr
[junit4:junit4]   2> 3310 T835 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3312 T835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3313 T835 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3313 T835 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3314 T835 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3363 T835 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3366 T835 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:33194
[junit4:junit4]   2> 3367 T835 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3367 T835 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157
[junit4:junit4]   2> 3367 T835 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157/'
[junit4:junit4]   2> 3378 T835 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3379 T835 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3379 T835 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157
[junit4:junit4]   2> 3379 T835 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157/solr.xml
[junit4:junit4]   2> 3379 T835 oasc.CoreContainer.<init> New CoreContainer 10530506
[junit4:junit4]   2> 3380 T835 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157/'
[junit4:junit4]   2> 3380 T835 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157/'
[junit4:junit4]   2> 3391 T835 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3398 T835 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:32944/solr
[junit4:junit4]   2> 3398 T835 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32944/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@586c39
[junit4:junit4]   2> 3399 T880 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32944
[junit4:junit4]   2> 3399 T835 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3399 T880 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:32944, initiating session
[junit4:junit4]   2> 3399 T837 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39135
[junit4:junit4]   2> 3400 T837 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39135
[junit4:junit4]   2> 3400 T839 oazs.NIOServerCnxn.finishSessionInit Established session 0x139e9adb43d0004 with negotiated timeout 5000 for client /127.0.0.1:39135
[junit4:junit4]   2> 3400 T880 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:32944, sessionid = 0x139e9adb43d0004, negotiated timeout = 5000
[junit4:junit4]   2> 3401 T881 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@586c39 name:ZooKeeperConnection Watcher:127.0.0.1:32944/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3401 T835 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3402 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3402 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3403 T835 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33194_solr
[junit4:junit4]   2> 3404 T840 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139e9adb43d0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:33194_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:33194_solr
[junit4:junit4]   2> 3405 T835 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33194_solr
[junit4:junit4]   2> 3417 T869 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3417 T855 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3417 T835 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3418 T835 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3418 T835 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3418 T835 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3419 T835 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3419 T835 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3419 T835 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3419 T835 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3420 T835 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3420 T835 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3425 T835 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3428 T835 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157/collection1
[junit4:junit4]   2> 3429 T835 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3429 T835 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3429 T835 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3430 T835 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157/collection1/'
[junit4:junit4]   2> 3431 T835 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty2-1348245242157/collection1/lib/README' to classloader
[junit4:junit4]   2> 3431 T835 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-jetty2-1348245242157/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3455 T835 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3490 T835 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3491 T835 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3495 T835 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3770 T856 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3771 T835 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3773 T855 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3773 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3773 T869 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3777 T835 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3780 T835 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3788 T835 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3791 T835 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3793 T835 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3794 T835 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3795 T835 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-jetty2-1348245242157/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty2/
[junit4:junit4]   2> 3795 T835 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9cef7
[junit4:junit4]   2> 3796 T835 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty2/index/
[junit4:junit4]   2> 3796 T835 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3797 T835 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty2/index forceNew:false
[junit4:junit4]   2> 3800 T835 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3800 T835 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3801 T835 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3805 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3806 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3806 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3807 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3808 T835 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3809 T835 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3810 T835 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3811 T835 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3813 T835 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3814 T835 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3815 T835 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3816 T835 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3817 T835 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3818 T835 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3819 T835 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3820 T835 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3821 T835 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3822 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3823 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3824 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3825 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3825 T835 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3826 T835 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3826 T835 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3827 T835 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3828 T835 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3829 T835 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3830 T835 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3832 T835 oass.SolrIndexSearcher.<init> Opening Searcher@d9524 main
[junit4:junit4]   2> 3833 T835 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3833 T835 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3834 T835 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3837 T835 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3867 T882 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d9524 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4275 T856 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4279 T855 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4279 T869 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4279 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4376 T835 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4377 T835 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:33194/solr shardId:shard1
[junit4:junit4]   2> 4390 T835 oasc.ZkController.register We are http://127.0.0.1:33194/solr/collection1/ and leader is http://127.0.0.1:41846/solr/collection1/
[junit4:junit4]   2> 4390 T835 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33194/solr
[junit4:junit4]   2> 4391 T835 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 4392 T835 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C18 name=collection1 org.apache.solr.core.SolrCore@1e886e0 url=http://127.0.0.1:33194/solr/collection1 node=127.0.0.1:33194_solr
[junit4:junit4]   2> 4393 T883 C18 P33194 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 4394 T883 C18 P33194 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 4393 T835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4398 T883 C18 P33194 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:41846/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 4399 T883 C18 P33194 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:33194/solr START replicas=[http://127.0.0.1:41846/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 4399 T835 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4399 T883 C18 P33194 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 4399 T835 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4402 T883 C18 P33194 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 4403 T883 C18 P33194 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 4402 T835 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4403 T883 C18 P33194 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C19 name=collection1 org.apache.solr.core.SolrCore@1a0fca3 url=http://127.0.0.1:41846/solr/collection1 node=127.0.0.1:41846_solr
[junit4:junit4]   2> 4410 T861 C19 P41846 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=1 
[junit4:junit4]   2> 4416 T835 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32944/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1446b80
[junit4:junit4]   2> 4417 T885 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32944
[junit4:junit4]   2> 4417 T835 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4418 T885 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:32944, initiating session
[junit4:junit4]   2> 4418 T837 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39143
[junit4:junit4]   2> 4418 T837 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39143
[junit4:junit4]   2> 4419 T839 oazs.NIOServerCnxn.finishSessionInit Established session 0x139e9adb43d0005 with negotiated timeout 10000 for client /127.0.0.1:39143
[junit4:junit4]   2> 4419 T885 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:32944, sessionid = 0x139e9adb43d0005, negotiated timeout = 10000
[junit4:junit4]   2> 4420 T886 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1446b80 name:ZooKeeperConnection Watcher:127.0.0.1:32944/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4420 T835 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4421 T835 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4423 T835 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4423 T835 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 4430 T835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C20 name=collection1 org.apache.solr.core.SolrCore@9741c2 url=http://127.0.0.1:53645/solr/collection1 node=127.0.0.1:53645_solr
[junit4:junit4]   2> 4444 T847 C20 P53645 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1348245238843/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4445 T847 C20 P53645 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4447 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10001 (1413737604219666432)]} 0 5
[junit4:junit4]   2> 4447 T847 C20 P53645 /update {wt=javabin&version=2} {add=[1 (1413737604217569280)]} 0 6
[junit4:junit4]   2> 4448 T888 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32944/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@14a2441
[junit4:junit4]   2> 4449 T889 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32944
[junit4:junit4]   2> 4449 T888 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4449 T889 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:32944, initiating session
[junit4:junit4]   2> 4449 T837 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:39147
[junit4:junit4]   2> 4449 T837 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:39147
[junit4:junit4]   2> 4450 T839 oazs.NIOServerCnxn.finishSessionInit Established session 0x139e9adb43d0006 with negotiated timeout 10000 for client /127.0.0.1:39147
[junit4:junit4]   2> 4450 T889 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:32944, sessionid = 0x139e9adb43d0006, negotiated timeout = 10000
[junit4:junit4]   2> 4450 T890 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14a2441 name:ZooKeeperConnection Watcher:127.0.0.1:32944/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4451 T888 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4451 T888 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4456 T864 C19 P41846 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4456 T864 C19 P41846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4459 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10001 (1413737604231200768)]} 0 6
[junit4:junit4]   2> 4461 T863 C19 P41846 /update {wt=javabin&version=2} {add=[1 (1413737604237492224)]} 0 1
[junit4:junit4]   2> 4462 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10002 (1413737604238540800)]} 0 1
[junit4:junit4]   2> 4464 T848 C20 P53645 /update {wt=javabin&version=2} {add=[2 (1413737604241686528)]} 0 1
[junit4:junit4]   2> 4465 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10002 (1413737604241686528)]} 0 1
[junit4:junit4]   2> 4467 T864 C19 P41846 /update {wt=javabin&version=2} {add=[2 (1413737604244832256)]} 0 1
[junit4:junit4]   2> 4468 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10002 (-1413737604245880832)]} 0 0
[junit4:junit4]   2> 4470 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[2 (-1413737604247977984)]} 0 0
[junit4:junit4]   2> 4471 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10002 (-1413737604249026560)]} 0 0
[junit4:junit4]   2> 4473 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[2 (-1413737604251123712)]} 0 1
[junit4:junit4]   2> 4475 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10003 (1413737604252172288)]} 0 1
[junit4:junit4]   2> 4477 T847 C20 P53645 /update {wt=javabin&version=2} {add=[3 (1413737604255318016)]} 0 1
[junit4:junit4]   2> 4477 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10003 (1413737604255318016)]} 0 0
[junit4:junit4]   2> 4480 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10004 (1413737604257415168)]} 0 1
[junit4:junit4]   2> 4480 T864 C19 P41846 /update {wt=javabin&version=2} {add=[3 (1413737604257415168)]} 0 1
[junit4:junit4]   2> 4482 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10004 (1413737604260560896)]} 0 0
[junit4:junit4]   2> 4482 T847 C20 P53645 /update {wt=javabin&version=2} {add=[4 (1413737604260560896)]} 0 0
[junit4:junit4]   2> 4485 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10005 (1413737604262658048)]} 0 1
[junit4:junit4]   2> 4485 T863 C19 P41846 /update {wt=javabin&version=2} {add=[4 (1413737604262658048)]} 0 1
[junit4:junit4]   2> 4487 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10005 (1413737604265803776)]} 0 0
[junit4:junit4]   2> 4487 T848 C20 P53645 /update {wt=javabin&version=2} {add=[5 (1413737604265803776)]} 0 0
[junit4:junit4]   2> 4490 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10006 (1413737604267900928)]} 0 1
[junit4:junit4]   2> 4494 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10006 (1413737604271046656)]} 0 2
[junit4:junit4]   2> 4496 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10007 (1413737604275240960)]} 0 0
[junit4:junit4]   2> 4499 T863 C19 P41846 /update {wt=javabin&version=2} {add=[5 (1413737604267900928)]} 0 10
[junit4:junit4]   2> 4499 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10007 (1413737604278386688)]} 0 1
[junit4:junit4]   2> 4501 T848 C20 P53645 /update {wt=javabin&version=2} {add=[6 (1413737604280483840)]} 0 0
[junit4:junit4]   2> 4502 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10008 (1413737604280483841)]} 0 1
[junit4:junit4]   2> 4504 T864 C19 P41846 /update {wt=javabin&version=2} {add=[6 (1413737604282580992)]} 0 1
[junit4:junit4]   2> 4504 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10008 (1413737604283629568)]} 0 0
[junit4:junit4]   2> 4507 T847 C20 P53645 /update {wt=javabin&version=2} {add=[7 (1413737604285726720)]} 0 1
[junit4:junit4]   2> 4507 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10009 (1413737604286775296)]} 0 1
[junit4:junit4]   2> 4510 T863 C19 P41846 /update {wt=javabin&version=2} {add=[7 (1413737604288872448)]} 0 1
[junit4:junit4]   2> 4510 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10009 (1413737604288872449)]} 0 1
[junit4:junit4]   2> 4513 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10010 (1413737604292018177)]} 0 1
[junit4:junit4]   2> 4515 T848 C20 P53645 /update {wt=javabin&version=2} {add=[8 (1413737604292018176)]} 0 3
[junit4:junit4]   2> 4516 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10010 (1413737604296212480)]} 0 1
[junit4:junit4]   2> 4517 T863 C19 P41846 /update {wt=javabin&version=2} {add=[8 (1413737604297261056)]} 0 0
[junit4:junit4]   2> 4519 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10005 (-1413737604298309632)]} 0 1
[junit4:junit4]   2> 4521 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10005 (-1413737604301455360)]} 0 0
[junit4:junit4]   2> 4530 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10011 (1413737604303552512)]} 0 7
[junit4:junit4]   2> 4533 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10011 (1413737604312989696)]} 0 1
[junit4:junit4]   2> 4535 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10007 (-1413737604316135424)]} 0 0
[junit4:junit4]   2> 4535 T847 C20 P53645 /update {wt=javabin&version=2} {add=[9 (1413737604300406784)]} 0 16
[junit4:junit4]   2> 4537 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10007 (-1413737604318232576)]} 0 0
[junit4:junit4]   2> 4541 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10012 (1413737604321378304)]} 0 2
[junit4:junit4]   2> 4548 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10012 (1413737604324524032)]} 0 6
[junit4:junit4]   2> 4550 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10008 (-1413737604331864064)]} 0 0
[junit4:junit4]   2> 4554 T864 C19 P41846 /update {wt=javabin&version=2} {add=[9 (1413737604319281152)]} 0 17
[junit4:junit4]   2> 4555 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10008 (-1413737604336058368)]} 0 1
[junit4:junit4]   2> 4557 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10 (1413737604339204096)]} 0 1
[junit4:junit4]   2> 4558 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10013 (1413737604339204097)]} 0 1
[junit4:junit4]   2> 4561 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10 (1413737604342349824)]} 0 1
[junit4:junit4]   2> 4561 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10013 (1413737604342349825)]} 0 1
[junit4:junit4]   2> 4563 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[5 (-1413737604345495552)]} 0 0
[junit4:junit4]   2> 4563 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10014 (1413737604345495553)]} 0 0
[junit4:junit4]   2> 4565 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[5 (-1413737604347592704)]} 0 0
[junit4:junit4]   2> 4566 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10014 (1413737604348641280)]} 0 1
[junit4:junit4]   2> 4568 T847 C20 P53645 /update {wt=javabin&version=2} {add=[11 (1413737604349689856)]} 0 1
[junit4:junit4]   2> 4569 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10010 (-1413737604351787008)]} 0 1
[junit4:junit4]   2> 4571 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10010 (-1413737604353884161)]} 0 0
[junit4:junit4]   2> 4571 T863 C19 P41846 /update {wt=javabin&version=2} {add=[11 (1413737604353884160)]} 0 1
[junit4:junit4]   2> 4573 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10015 (1413737604355981312)]} 0 0
[junit4:junit4]   2> 4573 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[7 (-1413737604355981313)]} 0 0
[junit4:junit4]   2> 4576 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10015 (1413737604358078464)]} 0 1
[junit4:junit4]   2> 4576 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[7 (-1413737604359127040)]} 0 1
[junit4:junit4]   2> 4578 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10015 (-1413737604361224192)]} 0 0
[junit4:junit4]   2> 4578 T848 C20 P53645 /update {wt=javabin&version=2} {add=[12 (1413737604361224193)]} 0 0
[junit4:junit4]   2> 4580 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10015 (-1413737604363321344)]} 0 0
[junit4:junit4]   2> 4581 T863 C19 P41846 /update {wt=javabin&version=2} {add=[12 (1413737604364369920)]} 0 1
[junit4:junit4]   2> 4582 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10016 (1413737604365418496)]} 0 0
[junit4:junit4]   2> 4583 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[8 (-1413737604366467072)]} 0 0
[junit4:junit4]   2> 4585 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10016 (1413737604367515648)]} 0 1
[junit4:junit4]   2> 4585 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[8 (-1413737604368564224)]} 0 0
[junit4:junit4]   2> 4587 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10016 (-1413737604370661376)]} 0 0
[junit4:junit4]   2> 4588 T848 C20 P53645 /update {wt=javabin&version=2} {add=[13 (1413737604370661377)]} 0 1
[junit4:junit4]   2> 4589 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10016 (-1413737604372758528)]} 0 0
[junit4:junit4]   2> 4590 T863 C19 P41846 /update {wt=javabin&version=2} {add=[13 (1413737604373807104)]} 0 1
[junit4:junit4]   2> 4591 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10017 (1413737604374855680)]} 0 0
[junit4:junit4]   2> 4593 T847 C20 P53645 /update {wt=javabin&version=2} {add=[14 (1413737604375904256)]} 0 1
[junit4:junit4]   2> 4596 T864 C19 P41846 /update {wt=javabin&version=2} {add=[14 (1413737604379049984)]} 0 2
[junit4:junit4]   2> 4598 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10 (-1413737604382195712)]} 0 1
[junit4:junit4]   2> 4600 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10 (-1413737604384292864)]} 0 0
[junit4:junit4]   2> 4600 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10017 (1413737604376952832)]} 0 7
[junit4:junit4]   2> 4602 T847 C20 P53645 /update {wt=javabin&version=2} {add=[15 (1413737604386390016)]} 0 0
[junit4:junit4]   2> 4602 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10018 (1413737604386390017)]} 0 0
[junit4:junit4]   2> 4605 T863 C19 P41846 /update {wt=javabin&version=2} {add=[15 (1413737604388487168)]} 0 1
[junit4:junit4]   2> 4605 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10018 (1413737604388487169)]} 0 1
[junit4:junit4]   2> 4607 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[15 (-1413737604391632896)]} 0 0
[junit4:junit4]   2> 4607 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10018 (-1413737604391632897)]} 0 0
[junit4:junit4]   2> 4609 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[15 (-1413737604393730048)]} 0 0
[junit4:junit4]   2> 4609 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10018 (-1413737604393730049)]} 0 0
[junit4:junit4]   2> 4611 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10019 (1413737604395827201)]} 0 0
[junit4:junit4]   2> 4611 T847 C20 P53645 /update {wt=javabin&version=2} {add=[16 (1413737604395827200)]} 0 0
[junit4:junit4]   2> 4614 T864 C19 P41846 /update {wt=javabin&version=2} {add=[16 (1413737604397924352)]} 0 1
[junit4:junit4]   2> 4614 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10019 (1413737604397924353)]} 0 1
[junit4:junit4]   2> 4616 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[16 (-1413737604401070080)]} 0 1
[junit4:junit4]   2> 4616 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10019 (-1413737604401070081)]} 0 0
[junit4:junit4]   2> 4618 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[16 (-1413737604403167232)]} 0 1
[junit4:junit4]   2> 4618 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10019 (-1413737604403167233)]} 0 0
[junit4:junit4]   2> 4620 T848 C20 P53645 /update {wt=javabin&version=2} {add=[17 (1413737604405264384)]} 0 0
[junit4:junit4]   2> 4621 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10020 (1413737604405264385)]} 0 1
[junit4:junit4]   2> 4623 T864 C19 P41846 /update {wt=javabin&version=2} {add=[17 (1413737604407361536)]} 0 1
[junit4:junit4]   2> 4623 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10020 (1413737604408410112)]} 0 0
[junit4:junit4]   2> 4625 T847 C20 P53645 /update {wt=javabin&version=2} {add=[18 (1413737604410507264)]} 0 0
[junit4:junit4]   2> 4627 T863 C19 P41846 /update {wt=javabin&version=2} {add=[18 (1413737604412604416)]} 0 0
[junit4:junit4]   2> 4630 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[18 (-1413737604414701568)]} 0 1
[junit4:junit4]   2> 4632 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[18 (-1413737604416798720)]} 0 1
[junit4:junit4]   2> 4633 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10021 (1413737604410507265)]} 0 8
[junit4:junit4]   2> 4635 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10021 (1413737604420993024)]} 0 0
[junit4:junit4]   2> 4638 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10022 (1413737604423090176)]} 0 1
[junit4:junit4]   2> 4640 T847 C20 P53645 /update {wt=javabin&version=2} {add=[19 (1413737604419944448)]} 0 7
[junit4:junit4]   2> 4643 T864 C19 P41846 /update {wt=javabin&version=2} {add=[19 (1413737604428333056)]} 0 1
[junit4:junit4]   2> 4646 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[19 (-1413737604431478784)]} 0 1
[junit4:junit4]   2> 4647 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10022 (1413737604426235904)]} 0 7
[junit4:junit4]   2> 4648 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[19 (-1413737604434624512)]} 0 1
[junit4:junit4]   2> 4650 T848 C20 P53645 /update {wt=javabin&version=2} {add=[20 (1413737604436721664)]} 0 0
[junit4:junit4]   2> 4650 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10023 (1413737604435673088)]} 0 1
[junit4:junit4]   2> 4653 T864 C19 P41846 /update {wt=javabin&version=2} {add=[20 (1413737604438818816)]} 0 1
[junit4:junit4]   2> 4653 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10023 (1413737604438818817)]} 0 1
[junit4:junit4]   2> 4655 T847 C20 P53645 /update {wt=javabin&version=2} {add=[21 (1413737604441964544)]} 0 0
[junit4:junit4]   2> 4656 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10024 (1413737604443013120)]} 0 1
[junit4:junit4]   2> 4658 T863 C19 P41846 /update {wt=javabin&version=2} {add=[21 (1413737604444061696)]} 0 1
[junit4:junit4]   2> 4659 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10024 (1413737604445110272)]} 0 1
[junit4:junit4]   2> 4660 T848 C20 P53645 /update {wt=javabin&version=2} {add=[22 (1413737604447207424)]} 0 1
[junit4:junit4]   2> 4661 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10023 (-1413737604448256000)]} 0 0
[junit4:junit4]   2> 4663 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10023 (-1413737604450353153)]} 0 0
[junit4:junit4]   2> 4664 T864 C19 P41846 /update {wt=javabin&version=2} {add=[22 (1413737604450353152)]} 0 2
[junit4:junit4]   2> 4665 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10025 (1413737604452450304)]} 0 0
[junit4:junit4]   2> 4666 T848 C20 P53645 /update {wt=javabin&version=2} {add=[23 (1413737604453498880)]} 0 0
[junit4:junit4]   2> 4668 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10025 (1413737604454547456)]} 0 1
[junit4:junit4]   2> 4669 T863 C19 P41846 /update {wt=javabin&version=2} {add=[23 (1413737604455596032)]} 0 1
[junit4:junit4]   2> 4671 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10026 (1413737604457693184)]} 0 1
[junit4:junit4]   2> 4671 T847 C20 P53645 /update {wt=javabin&version=2} {add=[24 (1413737604458741760)]} 0 1
[junit4:junit4]   2> 4673 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10026 (1413737604460838912)]} 0 1
[junit4:junit4]   2> 4675 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10027 (1413737604462936064)]} 0 0
[junit4:junit4]   2> 4678 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10027 (1413737604465033216)]} 0 1
[junit4:junit4]   2> 4681 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10028 (1413737604468178944)]} 0 1
[junit4:junit4]   2> 4681 T864 C19 P41846 /update {wt=javabin&version=2} {add=[24 (1413737604461887488)]} 0 8
[junit4:junit4]   2> 4683 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10028 (1413737604471324672)]} 0 0
[junit4:junit4]   2> 4683 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[23 (-1413737604471324672)]} 0 0
[junit4:junit4]   2> 4685 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10025 (-1413737604473421824)]} 0 0
[junit4:junit4]   2> 4686 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[23 (-1413737604474470400)]} 0 1
[junit4:junit4]   2> 4687 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10025 (-1413737604475518976)]} 0 0
[junit4:junit4]   2> 4688 T848 C20 P53645 /update {wt=javabin&version=2} {add=[25 (1413737604476567552)]} 0 1
[junit4:junit4]   2> 4690 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10029 (1413737604477616128)]} 0 1
[junit4:junit4]   2> 4691 T864 C19 P41846 /update {wt=javabin&version=2} {add=[25 (1413737604478664704)]} 0 1
[junit4:junit4]   2> 4692 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10029 (1413737604480761856)]} 0 0
[junit4:junit4]   2> 4693 T847 C20 P53645 /update {wt=javabin&version=2} {add=[26 (1413737604481810432)]} 0 1
[junit4:junit4]   2> 4694 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10027 (-1413737604482859008)]} 0 0
[junit4:junit4]   2> 4695 T863 C19 P41846 /update {wt=javabin&version=2} {add=[26 (1413737604483907584)]} 0 0
[junit4:junit4]   2> 4696 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10027 (-1413737604484956160)]} 0 0
[junit4:junit4]   2> 4698 T848 C20 P53645 /update {wt=javabin&version=2} {add=[27 (1413737604486004736)]} 0 1
[junit4:junit4]   2> 4698 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10030 (1413737604487053312)]} 0 0
[junit4:junit4]   2> 4700 T864 C19 P41846 /update {wt=javabin&version=2} {add=[27 (1413737604489150464)]} 0 0
[junit4:junit4]   2> 4701 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10030 (1413737604489150465)]} 0 1
[junit4:junit4]   2> 4703 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10028 (-1413737604492296192)]} 0 0
[junit4:junit4]   2> 4705 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10028 (-1413737604494393344)]} 0 0
[junit4:junit4]   2> 4707 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10031 (1413737604496490496)]} 0 0
[junit4:junit4]   2> 4710 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10031 (1413737604498587648)]} 0 1
[junit4:junit4]   2> 4710 T847 C20 P53645 /update {wt=javabin&version=2} {add=[28 (1413737604491247616)]} 0 8
[junit4:junit4]   2> 4713 T863 C19 P41846 /update {wt=javabin&version=2} {add=[28 (1413737604501733376)]} 0 1
[junit4:junit4]   2> 4716 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[25 (-1413737604505927680)]} 0 0
[junit4:junit4]   2> 4719 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[25 (-1413737604509073408)]} 0 0
[junit4:junit4]   2> 4721 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10032 (1413737604501733376)]} 0 9
[junit4:junit4]   2> 4726 T847 C20 P53645 /update {wt=javabin&version=2} {add=[29 (1413737604515364864)]} 0 1
[junit4:junit4]   2> 4730 T864 C19 P41846 /update {wt=javabin&version=2} {add=[29 (1413737604519559168)]} 0 2
[junit4:junit4]   2> 4733 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[27 (-1413737604523753472)]} 0 0
[junit4:junit4]   2> 4735 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10032 (1413737604514316288)]} 0 11
[junit4:junit4]   2> 4735 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[27 (-1413737604525850624)]} 0 0
[junit4:junit4]   2> 4738 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10033 (1413737604527947776)]} 0 1
[junit4:junit4]   2> 4739 T848 C20 P53645 /update {wt=javabin&version=2} {add=[30 (1413737604528996352)]} 0 2
[junit4:junit4]   2> 4740 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10033 (1413737604531093504)]} 0 0
[junit4:junit4]   2> 4742 T863 C19 P41846 /update {wt=javabin&version=2} {add=[30 (1413737604532142080)]} 0 1
[junit4:junit4]   2> 4743 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10034 (1413737604534239232)]} 0 1
[junit4:junit4]   2> 4744 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[28 (-1413737604535287808)]} 0 0
[junit4:junit4]   2> 4746 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10034 (1413737604536336384)]} 0 1
[junit4:junit4]   2> 4746 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[28 (-1413737604537384960)]} 0 0
[junit4:junit4]   2> 4748 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10035 (1413737604539482112)]} 0 1
[junit4:junit4]   2> 4749 T848 C20 P53645 /update {wt=javabin&version=2} {add=[31 (1413737604539482113)]} 0 1
[junit4:junit4]   2> 4750 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10035 (1413737604541579264)]} 0 0
[junit4:junit4]   2> 4751 T863 C19 P41846 /update {wt=javabin&version=2} {add=[31 (1413737604542627840)]} 0 0
[junit4:junit4]   2> 4753 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10036 (1413737604543676416)]} 0 1
[junit4:junit4]   2> 4753 T847 C20 P53645 /update {wt=javabin&version=2} {add=[32 (1413737604544724992)]} 0 0
[junit4:junit4]   2> 4755 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10036 (1413737604546822144)]} 0 1
[junit4:junit4]   2> 4756 T864 C19 P41846 /update {wt=javabin&version=2} {add=[32 (1413737604546822145)]} 0 1
[junit4:junit4]   2> 4757 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10034 (-1413737604548919296)]} 0 0
[junit4:junit4]   2> 4758 T848 C20 P53645 /update {wt=javabin&version=2} {add=[33 (1413737604549967872)]} 0 1
[junit4:junit4]   2> 4759 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10034 (-1413737604551016448)]} 0 0
[junit4:junit4]   2> 4760 T863 C19 P41846 /update {wt=javabin&version=2} {add=[33 (1413737604552065024)]} 0 0
[junit4:junit4]   2> 4761 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10037 (1413737604553113600)]} 0 0
[junit4:junit4]   2> 4763 T847 C20 P53645 /update {wt=javabin&version=2} {add=[34 (1413737604554162176)]} 0 1
[junit4:junit4]   2> 4766 T864 C19 P41846 /update {wt=javabin&version=2} {add=[34 (1413737604557307904)]} 0 1
[junit4:junit4]   2> 4772 T847 C20 P53645 /update {wt=javabin&version=2} {add=[35 (1413737604563599360)]} 0 1
[junit4:junit4]   2> 4775 T864 C19 P41846 /update {wt=javabin&version=2} {add=[35 (1413737604566745088)]} 0 1
[junit4:junit4]   2> 4775 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10037 (1413737604555210752)]} 0 12
[junit4:junit4]   2> 4777 T847 C20 P53645 /update {wt=javabin&version=2} {add=[36 (1413737604569890816)]} 0 1
[junit4:junit4]   2> 4777 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10038 (1413737604569890817)]} 0 0
[junit4:junit4]   2> 4779 T863 C19 P41846 /update {wt=javabin&version=2} {add=[36 (1413737604571987968)]} 0 0
[junit4:junit4]   2> 4780 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10038 (1413737604571987969)]} 0 1
[junit4:junit4]   2> 4781 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[34 (-1413737604574085120)]} 0 0
[junit4:junit4]   2> 4782 T856 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4782 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10039 (1413737604575133696)]} 0 1
[junit4:junit4]   2> 4784 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[34 (-1413737604577230848)]} 0 1
[junit4:junit4]   2> 4785 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10039 (1413737604577230849)]} 0 1
[junit4:junit4]   2> 4785 T855 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4785 T869 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4785 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4785 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4785 T890 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4786 T847 C20 P53645 /update {wt=javabin&version=2} {add=[37 (1413737604579328000)]} 0 0
[junit4:junit4]   2> 4787 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10040 (1413737604580376576)]} 0 0
[junit4:junit4]   2>  C18_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:33194_solr, base_url=http://127.0.0.1:33194/solr}
[junit4:junit4]   2> 4794 T874 C18 P33194 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1348245238843/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4795 T874 C18 P33194 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4796 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4797 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 4797 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10040 (1413737604582473728)]} 0 8
[junit4:junit4]   2> 4797 T863 C19 P41846 /update {wt=javabin&version=2} {add=[37 (1413737604581425152)]} 0 9
[junit4:junit4]   2> 4800 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10041 (1413737604592959489)]} 0 1
[junit4:junit4]   2> 4805 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4805 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10041 (1413737604596105216)]} 0 3
[junit4:junit4]   2> 4806 T847 C20 P53645 /update {wt=javabin&version=2} {add=[38 (1413737604592959488)]} 0 7
[junit4:junit4]   2> 4808 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10037 (-1413737604601348096)]} 0 1
[junit4:junit4]   2> 4811 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4811 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4811 T863 C19 P41846 /update {wt=javabin&version=2} {add=[38 (1413737604602396672)]} 0 3
[junit4:junit4]   2> 4812 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10037 (-1413737604603445248)]} 0 3
[junit4:junit4]   2> 4815 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10042 (1413737604608688128)]} 0 1
[junit4:junit4]   2> 4858 T848 C20 P53645 /update {wt=javabin&version=2} {add=[39 (1413737604607639552)]} 0 45
[junit4:junit4]   2> 4865 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4866 T863 C19 P41846 /update {wt=javabin&version=2} {add=[39 (1413737604657971200)]} 0 5
[junit4:junit4]   2> 4870 T848 C20 P53645 /update {wt=javabin&version=2} {add=[40 (1413737604665311232)]} 0 2
[junit4:junit4]   2> 4870 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4870 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10042 (1413737604611833856)]} 0 53
[junit4:junit4]   2> 4873 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10043 (1413737604670554112)]} 0 0
[junit4:junit4]   2> 4875 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4875 T863 C19 P41846 /update {wt=javabin&version=2} {add=[40 (1413737604669505536)]} 0 3
[junit4:junit4]   2> 4878 T848 C20 P53645 /update {wt=javabin&version=2} {add=[41 (1413737604675796992)]} 0 0
[junit4:junit4]   2> 4879 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4880 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10043 (1413737604674748416)]} 0 4
[junit4:junit4]   2> 4883 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10044 (1413737604681039872)]} 0 0
[junit4:junit4]   2> 4884 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4885 T863 C19 P41846 /update {wt=javabin&version=2} {add=[41 (1413737604678942720)]} 0 4
[junit4:junit4]   2> 4887 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[37 (-1413737604685234176)]} 0 0
[junit4:junit4]   2> 4892 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4892 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[37 (-1413737604687331328)]} 0 3
[junit4:junit4]   2> 4895 T848 C20 P53645 /update {wt=javabin&version=2} {add=[42 (1413737604693622784)]} 0 0
[junit4:junit4]   2> 4896 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 4897 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10044 (1413737604684185600)]} 0 11
[junit4:junit4]   2> 4899 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10038 (-1413737604697817088)]} 0 0
[junit4:junit4]   2> 4902 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4902 T863 C19 P41846 /update {wt=javabin&version=2} {add=[42 (1413737604696768512)]} 0 4
[junit4:junit4]   2> 4903 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4904 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10038 (-1413737604700962816)]} 0 3
[junit4:junit4]   2> 4905 T848 C20 P53645 /update {wt=javabin&version=2} {add=[43 (1413737604703059968)]} 0 1
[junit4:junit4]   2> 4907 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10045 (1413737604705157120)]} 0 1
[junit4:junit4]   2> 4913 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 4914 T864 C19 P41846 /update {wt=javabin&version=2} {add=[43 (1413737604706205696)]} 0 7
[junit4:junit4]   2> 4914 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4915 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10045 (1413737604708302848)]} 0 6
[junit4:junit4]   2> 4918 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10046 (1413737604716691456)]} 0 1
[junit4:junit4]   2> 4918 T847 C20 P53645 /update {wt=javabin&version=2} {add=[44 (1413737604716691457)]} 0 1
[junit4:junit4]   2> 4924 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4924 T863 C19 P41846 /update {wt=javabin&version=2} {add=[44 (1413737604720885760)]} 0 4
[junit4:junit4]   2> 4926 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[38 (-1413737604726128640)]} 0 0
[junit4:junit4]   2> 4930 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4931 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[38 (-1413737604728225792)]} 0 3
[junit4:junit4]   2> 4933 T847 C20 P53645 /update {wt=javabin&version=2} {add=[45 (1413737604733468672)]} 0 0
[junit4:junit4]   2> 4939 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4940 T863 C19 P41846 /update {wt=javabin&version=2} {add=[45 (1413737604735565824)]} 0 5
[junit4:junit4]   2> 4943 T847 C20 P53645 /update {wt=javabin&version=2} {add=[46 (1413737604742905856)]} 0 1
[junit4:junit4]   2> 4946 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4947 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10046 (1413737604720885761)]} 0 26
[junit4:junit4]   2> 4948 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4948 T863 C19 P41846 /update {wt=javabin&version=2} {add=[46 (1413737604746051584)]} 0 3
[junit4:junit4]   2> 4949 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10040 (-1413737604750245888)]} 0 0
[junit4:junit4]   2> 4950 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[40 (-1413737604751294464)]} 0 0
[junit4:junit4]   2> 4953 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4953 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10040 (-1413737604752343040)]} 0 2
[junit4:junit4]   2> 4955 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4956 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[40 (-1413737604753391616)]} 0 4
[junit4:junit4]   2> 4956 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10047 (1413737604757585920)]} 0 1
[junit4:junit4]   2> 4959 T847 C20 P53645 /update {wt=javabin&version=2} {add=[47 (1413737604759683072)]} 0 1
[junit4:junit4]   2> 4961 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4961 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10047 (1413737604759683072)]} 0 3
[junit4:junit4]   2> 4963 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4963 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10043 (-1413737604764925952)]} 0 0
[junit4:junit4]   2> 4963 T863 C19 P41846 /update {wt=javabin&version=2} {add=[47 (1413737604762828800)]} 0 2
[junit4:junit4]   2> 4965 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[43 (-1413737604767023104)]} 0 0
[junit4:junit4]   2> 4967 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4967 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10043 (-1413737604767023104)]} 0 2
[junit4:junit4]   2> 4969 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4970 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[43 (-1413737604770168832)]} 0 3
[junit4:junit4]   2> 4970 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10048 (1413737604772265984)]} 0 1
[junit4:junit4]   2> 4972 T848 C20 P53645 /update {wt=javabin&version=2} {add=[48 (1413737604774363136)]} 0 0
[junit4:junit4]   2> 4979 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 4979 T864 C19 P41846 /update {wt=javabin&version=2} {add=[48 (1413737604776460288)]} 0 5
[junit4:junit4]   2> 4982 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[44 (-1413737604783800320)]} 0 1
[junit4:junit4]   2> 4984 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 4984 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10048 (1413737604774363136)]} 0 12
[junit4:junit4]   2> 4985 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4986 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[44 (-1413737604786946048)]} 0 3
[junit4:junit4]   2> 4986 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10044 (-1413737604789043200)]} 0 0
[junit4:junit4]   2> 4988 T847 C20 P53645 /update {wt=javabin&version=2} {add=[49 (1413737604791140352)]} 0 0
[junit4:junit4]   2> 4990 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4990 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10044 (-1413737604791140352)]} 0 2
[junit4:junit4]   2> 4992 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4992 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10049 (1413737604795334656)]} 0 0
[junit4:junit4]   2> 4993 T863 C19 P41846 /update {wt=javabin&version=2} {add=[49 (1413737604793237504)]} 0 3
[junit4:junit4]   2> 4995 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[45 (-1413737604798480384)]} 0 0
[junit4:junit4]   2> 4997 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4997 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10049 (1413737604798480384)]} 0 3
[junit4:junit4]   2> 4999 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4999 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[45 (-1413737604800577536)]} 0 2
[junit4:junit4]   2> 4999 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10045 (-1413737604802674688)]} 0 0
[junit4:junit4]   2> 5003 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5004 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10045 (-1413737604804771840)]} 0 3
[junit4:junit4]   2> 5010 T848 C20 P53645 /update {wt=javabin&version=2} {add=[50 (1413737604804771840)]} 0 9
[junit4:junit4]   2> 5017 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5018 T863 C19 P41846 /update {wt=javabin&version=2} {add=[50 (1413737604818403328)]} 0 5
[junit4:junit4]   2> 5019 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10050 (1413737604810014720)]} 0 14
[junit4:junit4]   2> 5021 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[47 (-1413737604825743360)]} 0 1
[junit4:junit4]   2> 5025 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5026 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[47 (-1413737604827840512)]} 0 3
[junit4:junit4]   2> 5029 T848 C20 P53645 /update {wt=javabin&version=2} {add=[51 (1413737604833083392)]} 0 1
[junit4:junit4]   2> 5033 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5033 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10050 (1413737604826791936)]} 0 11
[junit4:junit4]   2> 5035 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5035 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10047 (-1413737604840423424)]} 0 0
[junit4:junit4]   2> 5036 T864 C19 P41846 /update {wt=javabin&version=2} {add=[51 (1413737604837277696)]} 0 5
[junit4:junit4]   2> 5038 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[48 (-1413737604843569152)]} 0 0
[junit4:junit4]   2> 5039 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5039 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10047 (-1413737604842520576)]} 0 2
[junit4:junit4]   2> 5041 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5042 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10051 (1413737604846714880)]} 0 1
[junit4:junit4]   2> 5042 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[48 (-1413737604845666304)]} 0 2
[junit4:junit4]   2> 5044 T848 C20 P53645 /update {wt=javabin&version=2} {add=[52 (1413737604849860608)]} 0 0
[junit4:junit4]   2> 5046 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5047 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10051 (1413737604849860608)]} 0 3
[junit4:junit4]   2> 5049 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10048 (-1413737604855103488)]} 0 0
[junit4:junit4]   2> 5049 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5050 T864 C19 P41846 /update {wt=javabin&version=2} {add=[52 (1413737604851957760)]} 0 4
[junit4:junit4]   2> 5052 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[49 (-1413737604858249216)]} 0 0
[junit4:junit4]   2> 5053 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5053 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10048 (-1413737604857200640)]} 0 2
[junit4:junit4]   2> 5055 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10052 (1413737604861394944)]} 0 0
[junit4:junit4]   2> 5055 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5056 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[49 (-1413737604860346368)]} 0 2
[junit4:junit4]   2> 5058 T848 C20 P53645 /update {wt=javabin&version=2} {add=[53 (1413737604864540672)]} 0 0
[junit4:junit4]   2> 5060 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5060 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10052 (1413737604863492096)]} 0 3
[junit4:junit4]   2> 5062 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10049 (-1413737604868734976)]} 0 0
[junit4:junit4]   2> 5066 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5066 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10049 (-1413737604870832128)]} 0 2
[junit4:junit4]   2> 5069 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10053 (1413737604876075008)]} 0 1
[junit4:junit4]   2> 5071 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 5072 T864 C19 P41846 /update {wt=javabin&version=2} {add=[53 (1413737604866637824)]} 0 12
[junit4:junit4]   2> 5074 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[50 (-1413737604881317888)]} 0 1
[junit4:junit4]   2> 5074 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5075 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10053 (1413737604878172160)]} 0 4
[junit4:junit4]   2> 5077 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10050 (-1413737604883415040)]} 0 1
[junit4:junit4]   2> 5077 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5078 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[50 (-1413737604883415040)]} 0 3
[junit4:junit4]   2> 5080 T848 C20 P53645 /update {wt=javabin&version=2} {add=[54 (1413737604887609344)]} 0 0
[junit4:junit4]   2> 5080 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5081 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10050 (-1413737604886560768)]} 0 2
[junit4:junit4]   2> 5083 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10054 (1413737604890755072)]} 0 0
[junit4:junit4]   2> 5085 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5086 T864 C19 P41846 /update {wt=javabin&version=2} {add=[54 (1413737604889706496)]} 0 4
[junit4:junit4]   2> 5088 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5088 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[51 (-1413737604895997952)]} 0 0
[junit4:junit4]   2> 5088 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10054 (1413737604892852224)]} 0 3
[junit4:junit4]   2> 5091 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10051 (-1413737604899143680)]} 0 1
[junit4:junit4]   2> 5092 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5092 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[51 (-1413737604898095104)]} 0 2
[junit4:junit4]   2> 5094 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5095 T848 C20 P53645 /update {wt=javabin&version=2} {add=[55 (1413737604902289408)]} 0 1
[junit4:junit4]   2> 5095 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10051 (-1413737604900192256)]} 0 3
[junit4:junit4]   2> 5099 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5100 T863 C19 P41846 /update {wt=javabin&version=2} {add=[55 (1413737604905435136)]} 0 3
[junit4:junit4]   2> 5102 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[55 (-1413737604910678016)]} 0 0
[junit4:junit4]   2> 5105 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5106 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[55 (-1413737604912775168)]} 0 3
[junit4:junit4]   2> 5107 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10055 (1413737604905435136)]} 0 10
[junit4:junit4]   2> 5109 T847 C20 P53645 /update {wt=javabin&version=2} {add=[56 (1413737604916969472)]} 0 1
[junit4:junit4]   2> 5114 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5115 T864 C19 P41846 /update {wt=javabin&version=2} {add=[56 (1413737604920115200)]} 0 4
[junit4:junit4]   2> 5117 T847 C20 P53645 /update {wt=javabin&version=2} {add=[57 (1413737604926406656)]} 0 0
[junit4:junit4]   2> 5122 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5122 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10055 (1413737604918018048)]} 0 13
[junit4:junit4]   2> 5122 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5123 T864 C19 P41846 /update {wt=javabin&version=2} {add=[57 (1413737604928503808)]} 0 4
[junit4:junit4]   2> 5124 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10055 (-1413737604933746688)]} 0 0
[junit4:junit4]   2> 5125 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[56 (-1413737604933746689)]} 0 1
[junit4:junit4]   2> 5128 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5128 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5128 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10055 (-1413737604935843840)]} 0 2
[junit4:junit4]   2> 5129 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[56 (-1413737604936892416)]} 0 3
[junit4:junit4]   2> 5131 T847 C20 P53645 /update {wt=javabin&version=2} {add=[58 (1413737604941086720)]} 0 1
[junit4:junit4]   2> 5131 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10056 (1413737604940038144)]} 0 1
[junit4:junit4]   2> 5135 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5136 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5136 T863 C19 P41846 /update {wt=javabin&version=2} {add=[58 (1413737604943183872)]} 0 3
[junit4:junit4]   2> 5136 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10056 (1413737604943183873)]} 0 3
[junit4:junit4]   2> 5138 T848 C20 P53645 /update {wt=javabin&version=2} {add=[59 (1413737604948426752)]} 0 0
[junit4:junit4]   2> 5139 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10057 (1413737604948426753)]} 0 1
[junit4:junit4]   2> 5146 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5147 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10057 (1413737604952621056)]} 0 6
[junit4:junit4]   2> 5150 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10056 (-1413737604961009664)]} 0 1
[junit4:junit4]   2> 5151 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 5151 T864 C19 P41846 /update {wt=javabin&version=2} {add=[59 (1413737604950523904)]} 0 11
[junit4:junit4]   2> 5154 T847 C20 P53645 /update {wt=javabin&version=2} {add=[60 (1413737604964155392)]} 0 1
[junit4:junit4]   2> 5154 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5155 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10056 (-1413737604963106816)]} 0 3
[junit4:junit4]   2> 5158 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10058 (1413737604969398272)]} 0 1
[junit4:junit4]   2> 5159 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5160 T864 C19 P41846 /update {wt=javabin&version=2} {add=[60 (1413737604967301120)]} 0 4
[junit4:junit4]   2> 5163 T847 C20 P53645 /update {wt=javabin&version=2} {add=[61 (1413737604973592576)]} 0 1
[junit4:junit4]   2> 5163 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5163 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10058 (1413737604971495424)]} 0 3
[junit4:junit4]   2> 5166 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10059 (1413737604976738304)]} 0 1
[junit4:junit4]   2> 5167 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5168 T864 C19 P41846 /update {wt=javabin&version=2} {add=[61 (1413737604976738304)]} 0 3
[junit4:junit4]   2> 5170 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[58 (-1413737604981981184)]} 0 1
[junit4:junit4]   2> 5175 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5175 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[58 (-1413737604984078336)]} 0 3
[junit4:junit4]   2> 5178 T847 C20 P53645 /update {wt=javabin&version=2} {add=[62 (1413737604990369792)]} 0 0
[junit4:junit4]   2> 5179 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5181 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10059 (1413737604979884032)]} 0 13
[junit4:junit4]   2> 5186 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5186 T864 C19 P41846 /update {wt=javabin&version=2} {add=[62 (1413737604993515520)]} 0 5
[junit4:junit4]   2> 5188 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[59 (-1413737605000855552)]} 0 0
[junit4:junit4]   2> 5191 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10060 (1413737604995612672)]} 0 8
[junit4:junit4]   2> 5192 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5193 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[59 (-1413737605002952704)]} 0 3
[junit4:junit4]   2> 5195 T847 C20 P53645 /update {wt=javabin&version=2} {add=[63 (1413737605007147008)]} 0 1
[junit4:junit4]   2> 5196 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5196 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10060 (1413737605006098432)]} 0 3
[junit4:junit4]   2> 5199 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10061 (1413737605011341312)]} 0 1
[junit4:junit4]   2> 5200 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5200 T864 C19 P41846 /update {wt=javabin&version=2} {add=[63 (1413737605010292736)]} 0 3
[junit4:junit4]   2> 5202 T847 C20 P53645 /update {wt=javabin&version=2} {add=[64 (1413737605015535616)]} 0 0
[junit4:junit4]   2> 5203 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5203 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10061 (1413737605013438464)]} 0 3
[junit4:junit4]   2> 5205 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10058 (-1413737605018681344)]} 0 0
[junit4:junit4]   2> 5209 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5209 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10058 (-1413737605020778496)]} 0 2
[junit4:junit4]   2> 5212 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10062 (1413737605024972800)]} 0 1
[junit4:junit4]   2> 5213 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 5213 T864 C19 P41846 /update {wt=javabin&version=2} {add=[64 (1413737605017632768)]} 0 9
[junit4:junit4]   2> 5215 T847 C20 P53645 /update {wt=javabin&version=2} {add=[65 (1413737605029167104)]} 0 0
[junit4:junit4]   2> 5217 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5217 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10062 (1413737605028118528)]} 0 3
[junit4:junit4]   2> 5219 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10059 (-1413737605033361408)]} 0 0
[junit4:junit4]   2> 5220 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5220 T864 C19 P41846 /update {wt=javabin&version=2} {add=[65 (1413737605031264256)]} 0 3
[junit4:junit4]   2> 5223 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[60 (-1413737605036507136)]} 0 1
[junit4:junit4]   2> 5223 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5223 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10059 (-1413737605035458560)]} 0 2
[junit4:junit4]   2> 5226 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10063 (1413737605040701440)]} 0 0
[junit4:junit4]   2> 5226 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5227 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[60 (-1413737605039652864)]} 0 3
[junit4:junit4]   2> 5229 T847 C20 P53645 /update {wt=javabin&version=2} {add=[66 (1413737605043847168)]} 0 0
[junit4:junit4]   2> 5231 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5231 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10063 (1413737605042798592)]} 0 3
[junit4:junit4]   2> 5235 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10064 (1413737605049090048)]} 0 1
[junit4:junit4]   2> 5235 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5236 T864 C19 P41846 /update {wt=javabin&version=2} {add=[66 (1413737605046992896)]} 0 4
[junit4:junit4]   2> 5239 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[61 (-1413737605054332928)]} 0 1
[junit4:junit4]   2> 5244 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5245 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[61 (-1413737605056430080)]} 0 4
[junit4:junit4]   2> 5248 T847 C20 P53645 /update {wt=javabin&version=2} {add=[67 (1413737605062721536)]} 0 1
[junit4:junit4]   2> 5253 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5254 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10064 (1413737605052235776)]} 0 17
[junit4:junit4]   2> 5256 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5256 T864 C19 P41846 /update {wt=javabin&version=2} {add=[67 (1413737605066915840)]} 0 5
[junit4:junit4]   2> 5260 T848 C20 P53645 /update {wt=javabin&version=2} {add=[68 (1413737605075304448)]} 0 1
[junit4:junit4]   2> 5266 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5267 T864 C19 P41846 /update {wt=javabin&version=2} {add=[68 (1413737605079498752)]} 0 5
[junit4:junit4]   2> 5269 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10065 (1413737605073207296)]} 0 13
[junit4:junit4]   2> 5270 T848 C20 P53645 /update {wt=javabin&version=2} {add=[69 (1413737605085790208)]} 0 1
[junit4:junit4]   2> 5275 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5275 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10065 (1413737605087887360)]} 0 4
[junit4:junit4]   2> 5275 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5276 T863 C19 P41846 /update {wt=javabin&version=2} {add=[69 (1413737605089984512)]} 0 4
[junit4:junit4]   2> 5278 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10060 (-1413737605095227392)]} 0 1
[junit4:junit4]   2> 5278 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[62 (-1413737605095227393)]} 0 0
[junit4:junit4]   2> 5283 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5283 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10060 (-1413737605097324544)]} 0 3
[junit4:junit4]   2> 5284 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5284 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[62 (-1413737605098373120)]} 0 3
[junit4:junit4]   2> 5287 T848 C20 P53645 /update {wt=javabin&version=2} {add=[70 (1413737605104664576)]} 0 0
[junit4:junit4]   2> 5288 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10066 (1413737605103616000)]} 0 2
[junit4:junit4]   2> 5295 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5296 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10066 (1413737605108858880)]} 0 6
[junit4:junit4]   2> 5299 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10061 (-1413737605117247488)]} 0 1
[junit4:junit4]   2> 5304 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5304 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10061 (-1413737605119344640)]} 0 3
[junit4:junit4]   2> 5305 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=13 
[junit4:junit4]   2> 5305 T864 C19 P41846 /update {wt=javabin&version=2} {add=[70 (1413737605107810304)]} 0 15
[junit4:junit4]   2> 5307 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10067 (1413737605124587520)]} 0 1
[junit4:junit4]   2> 5307 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[63 (-1413737605125636096)]} 0 0
[junit4:junit4]   2> 5311 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5311 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[63 (-1413737605127733248)]} 0 2
[junit4:junit4]   2> 5311 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5312 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10067 (1413737605127733249)]} 0 3
[junit4:junit4]   2> 5313 T848 C20 P53645 /update {wt=javabin&version=2} {add=[71 (1413737605131927552)]} 0 0
[junit4:junit4]   2> 5314 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10068 (1413737605131927553)]} 0 1
[junit4:junit4]   2> 5318 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5318 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5318 T864 C19 P41846 /update {wt=javabin&version=2} {add=[71 (1413737605134024704)]} 0 3
[junit4:junit4]   2> 5318 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10068 (1413737605135073280)]} 0 2
[junit4:junit4]   2> 5321 T847 C20 P53645 /update {wt=javabin&version=2} {add=[72 (1413737605140316160)]} 0 1
[junit4:junit4]   2> 5321 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10069 (1413737605140316161)]} 0 0
[junit4:junit4]   2> 5327 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5328 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10069 (1413737605142413313)]} 0 5
[junit4:junit4]   2> 5330 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10062 (-1413737605149753344)]} 0 0
[junit4:junit4]   2> 5334 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5334 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10062 (-1413737605151850496)]} 0 2
[junit4:junit4]   2> 5335 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5335 T863 C19 P41846 /update {wt=javabin&version=2} {add=[72 (1413737605142413312)]} 0 12
[junit4:junit4]   2> 5337 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10070 (1413737605157093376)]} 0 0
[junit4:junit4]   2> 5338 T847 C20 P53645 /update {wt=javabin&version=2} {add=[73 (1413737605157093377)]} 0 1
[junit4:junit4]   2> 5342 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5343 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5343 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10070 (1413737605160239104)]} 0 3
[junit4:junit4]   2> 5344 T864 C19 P41846 /update {wt=javabin&version=2} {add=[73 (1413737605160239105)]} 0 4
[junit4:junit4]   2> 5346 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10063 (-1413737605165481984)]} 0 1
[junit4:junit4]   2> 5346 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[65 (-1413737605166530560)]} 0 0
[junit4:junit4]   2> 5350 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5350 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5351 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10063 (-1413737605168627712)]} 0 3
[junit4:junit4]   2> 5351 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[65 (-1413737605168627713)]} 0 3
[junit4:junit4]   2> 5354 T847 C20 P53645 /update {wt=javabin&version=2} {add=[74 (1413737605173870593)]} 0 1
[junit4:junit4]   2> 5354 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10071 (1413737605173870592)]} 0 1
[junit4:junit4]   2> 5360 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5361 T863 C19 P41846 /update {wt=javabin&version=2} {add=[74 (1413737605177016321)]} 0 5
[junit4:junit4]   2> 5369 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 5370 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10071 (1413737605177016320)]} 0 14
[junit4:junit4]   2> 5372 T848 C20 P53645 /update {wt=javabin&version=2} {add=[75 (1413737605185404928)]} 0 9
[junit4:junit4]   2> 5373 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10072 (1413737605193793536)]} 0 1
[junit4:junit4]   2> 5378 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5378 T864 C19 P41846 /update {wt=javabin&version=2} {add=[75 (1413737605196939264)]} 0 3
[junit4:junit4]   2> 5379 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5380 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10072 (1413737605197987840)]} 0 4
[junit4:junit4]   2> 5381 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[67 (-1413737605203230720)]} 0 0
[junit4:junit4]   2> 5383 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10073 (1413737605204279296)]} 0 1
[junit4:junit4]   2> 5385 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5386 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[67 (-1413737605205327872)]} 0 3
[junit4:junit4]   2> 5390 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5390 T848 C20 P53645 /update {wt=javabin&version=2} {add=[76 (1413737605211619328)]} 0 1
[junit4:junit4]   2> 5390 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10073 (1413737605208473600)]} 0 4
[junit4:junit4]   2> 5393 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10065 (-1413737605215813632)]} 0 0
[junit4:junit4]   2> 5395 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5396 T863 C19 P41846 /update {wt=javabin&version=2} {add=[76 (1413737605214765056)]} 0 4
[junit4:junit4]   2> 5397 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5397 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10065 (-1413737605217910784)]} 0 2
[junit4:junit4]   2> 5398 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[68 (-1413737605221056512)]} 0 0
[junit4:junit4]   2> 5400 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10074 (1413737605222105088)]} 0 1
[junit4:junit4]   2> 5402 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5403 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[68 (-1413737605223153664)]} 0 3
[junit4:junit4]   2> 5406 T847 C20 P53645 /update {wt=javabin&version=2} {add=[77 (1413737605228396544)]} 0 1
[junit4:junit4]   2> 5412 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5412 T864 C19 P41846 /update {wt=javabin&version=2} {add=[77 (1413737605232590848)]} 0 3
[junit4:junit4]   2> 5447 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5448 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10074 (1413737605225250816)]} 0 46
[junit4:junit4]   2> 5450 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10075 (1413737605275582464)]} 0 1
[junit4:junit4]   2> 5454 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5454 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10075 (1413737605277679616)]} 0 2
[junit4:junit4]   2> 5457 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10067 (-1413737605281873920)]} 0 1
[junit4:junit4]   2> 5458 T847 C20 P53645 /update {wt=javabin&version=2} {add=[78 (1413737605237833728)]} 0 44
[junit4:junit4]   2> 5461 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5461 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10067 (-1413737605285019648)]} 0 2
[junit4:junit4]   2> 5463 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5463 T864 C19 P41846 /update {wt=javabin&version=2} {add=[78 (1413737605286068224)]} 0 3
[junit4:junit4]   2> 5464 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10076 (1413737605289213952)]} 0 1
[junit4:junit4]   2> 5466 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[69 (-1413737605292359680)]} 0 1
[junit4:junit4]   2> 5468 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5469 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10076 (1413737605292359680)]} 0 3
[junit4:junit4]   2> 5470 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5470 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[69 (-1413737605294456832)]} 0 2
[junit4:junit4]   2> 5471 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10068 (-1413737605297602560)]} 0 0
[junit4:junit4]   2> 5472 T847 C20 P53645 /update {wt=javabin&version=2} {add=[79 (1413737605298651136)]} 0 0
[junit4:junit4]   2> 5475 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5475 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10068 (-1413737605299699712)]} 0 2
[junit4:junit4]   2> 5478 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10077 (1413737605303894016)]} 0 1
[junit4:junit4]   2> 5483 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5484 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10077 (1413737605307039744)]} 0 4
[junit4:junit4]   2> 5486 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10078 (1413737605313331200)]} 0 0
[junit4:junit4]   2> 5492 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5492 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10078 (1413737605316476928)]} 0 3
[junit4:junit4]   2> 5495 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10069 (-1413737605322768384)]} 0 1
[junit4:junit4]   2> 5499 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5499 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10069 (-1413737605324865536)]} 0 2
[junit4:junit4]   2> 5502 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10079 (1413737605330108416)]} 0 0
[junit4:junit4]   2> 5507 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5507 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10079 (1413737605332205568)]} 0 3
[junit4:junit4]   2> 5509 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10071 (-1413737605337448448)]} 0 0
[junit4:junit4]   2> 5513 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5513 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10071 (-1413737605339545600)]} 0 2
[junit4:junit4]   2> 5516 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10080 (1413737605343739904)]} 0 1
[junit4:junit4]   2> 5520 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5520 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10080 (1413737605346885632)]} 0 3
[junit4:junit4]   2> 5522 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10072 (-1413737605351079936)]} 0 0
[junit4:junit4]   2> 5526 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5527 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10072 (-1413737605353177088)]} 0 3
[junit4:junit4]   2> 5529 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=52 
[junit4:junit4]   2> 5529 T864 C19 P41846 /update {wt=javabin&version=2} {add=[79 (1413737605300748288)]} 0 55
[junit4:junit4]   2> 5530 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10081 (1413737605358419968)]} 0 1
[junit4:junit4]   2> 5531 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[71 (-1413737605360517120)]} 0 0
[junit4:junit4]   2> 5535 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5536 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[71 (-1413737605362614272)]} 0 3
[junit4:junit4]   2> 5539 T848 C20 P53645 /update {wt=javabin&version=2} {add=[80 (1413737605367857152)]} 0 1
[junit4:junit4]   2> 5542 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5543 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10081 (1413737605361565696)]} 0 12
[junit4:junit4]   2> 5545 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5546 T863 C19 P41846 /update {wt=javabin&version=2} {add=[80 (1413737605371002880)]} 0 5
[junit4:junit4]   2> 5546 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10082 (1413737605376245760)]} 0 0
[junit4:junit4]   2> 5548 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[72 (-1413737605378342912)]} 0 0
[junit4:junit4]   2> 5551 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5552 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10082 (1413737605379391488)]} 0 3
[junit4:junit4]   2> 5552 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5553 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[72 (-1413737605380440064)]} 0 3
[junit4:junit4]   2> 5554 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10074 (-1413737605384634368)]} 0 0
[junit4:junit4]   2> 5555 T848 C20 P53645 /update {wt=javabin&version=2} {add=[81 (1413737605385682944)]} 0 1
[junit4:junit4]   2> 5557 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5558 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10074 (-1413737605386731520)]} 0 3
[junit4:junit4]   2> 5559 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5559 T863 C19 P41846 /update {wt=javabin&version=2} {add=[81 (1413737605387780096)]} 0 2
[junit4:junit4]   2> 5560 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10083 (1413737605390925824)]} 0 1
[junit4:junit4]   2> 5562 T847 C20 P53645 /update {wt=javabin&version=2} {add=[82 (1413737605391974400)]} 0 1
[junit4:junit4]   2> 5564 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5565 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10083 (1413737605393022976)]} 0 3
[junit4:junit4]   2> 5567 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10084 (1413737605398265856)]} 0 0
[junit4:junit4]   2> 5572 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5572 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10084 (1413737605400363008)]} 0 3
[junit4:junit4]   2> 5583 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10085 (1413737605405605888)]} 0 9
[junit4:junit4]   2> 5587 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5588 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10085 (1413737605417140224)]} 0 3
[junit4:junit4]   2> 5591 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10086 (1413737605421334528)]} 0 2
[junit4:junit4]   2> 5593 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=27 
[junit4:junit4]   2> 5594 T864 C19 P41846 /update {wt=javabin&version=2} {add=[82 (1413737605395120128)]} 0 30
[junit4:junit4]   2> 5595 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5595 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10086 (1413737605425528832)]} 0 2
[junit4:junit4]   2> 5596 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[74 (-1413737605428674560)]} 0 0
[junit4:junit4]   2> 5598 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10087 (1413737605430771712)]} 0 0
[junit4:junit4]   2> 5600 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5600 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[74 (-1413737605430771712)]} 0 2
[junit4:junit4]   2> 5603 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5603 T848 C20 P53645 /update {wt=javabin&version=2} {add=[83 (1413737605434966016)]} 0 1
[junit4:junit4]   2> 5603 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10087 (1413737605432868864)]} 0 3
[junit4:junit4]   2> 5618 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10088 (1413737605438111744)]} 0 13
[junit4:junit4]   2> 5620 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5621 T863 C19 P41846 /update {wt=javabin&version=2} {add=[83 (1413737605438111744)]} 0 17
[junit4:junit4]   2> 5626 T848 C20 P53645 /update {wt=javabin&version=2} {add=[84 (1413737605458034688)]} 0 2
[junit4:junit4]   2> 5626 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5627 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10088 (1413737605454888960)]} 0 6
[junit4:junit4]   2> 5630 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10076 (-1413737605463277568)]} 0 1
[junit4:junit4]   2> 5632 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5633 T863 C19 P41846 /update {wt=javabin&version=2} {add=[84 (1413737605462228992)]} 0 5
[junit4:junit4]   2> 5635 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5636 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10076 (-1413737605466423296)]} 0 4
[junit4:junit4]   2> 5637 T848 C20 P53645 /update {wt=javabin&version=2} {add=[85 (1413737605470617600)]} 0 2
[junit4:junit4]   2> 5640 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10089 (1413737605473763328)]} 0 1
[junit4:junit4]   2> 5643 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5644 T864 C19 P41846 /update {wt=javabin&version=2} {add=[85 (1413737605474811904)]} 0 5
[junit4:junit4]   2> 5646 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5647 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10089 (1413737605477957632)]} 0 5
[junit4:junit4]   2> 5648 T847 C20 P53645 /update {wt=javabin&version=2} {add=[86 (1413737605482151936)]} 0 1
[junit4:junit4]   2> 5651 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10090 (1413737605485297664)]} 0 1
[junit4:junit4]   2> 5658 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5659 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10090 (1413737605488443392)]} 0 6
[junit4:junit4]   2> 5662 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10077 (-1413737605497880576)]} 0 0
[junit4:junit4]   2> 5667 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5667 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10077 (-1413737605501026304)]} 0 2
[junit4:junit4]   2> 5667 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=13 
[junit4:junit4]   2> 5668 T863 C19 P41846 /update {wt=javabin&version=2} {add=[86 (1413737605486346240)]} 0 17
[junit4:junit4]   2> 5669 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10091 (1413737605505220608)]} 0 0
[junit4:junit4]   2> 5670 T847 C20 P53645 /update {wt=javabin&version=2} {add=[87 (1413737605506269184)]} 0 1
[junit4:junit4]   2> 5674 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5674 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5675 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10091 (1413737605508366336)]} 0 3
[junit4:junit4]   2> 5675 T864 C19 P41846 /update {wt=javabin&version=2} {add=[87 (1413737605508366337)]} 0 3
[junit4:junit4]   2> 5677 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10092 (1413737605513609216)]} 0 1
[junit4:junit4]   2> 5677 T848 C20 P53645 /update {wt=javabin&version=2} {add=[88 (1413737605513609217)]} 0 0
[junit4:junit4]   2> 5682 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5682 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10092 (1413737605515706368)]} 0 3
[junit4:junit4]   2> 5684 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10079 (-1413737605520949248)]} 0 0
[junit4:junit4]   2> 5688 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5688 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5688 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10079 (-1413737605523046400)]} 0 2
[junit4:junit4]   2> 5689 T863 C19 P41846 /update {wt=javabin&version=2} {add=[88 (1413737605515706369)]} 0 10
[junit4:junit4]   2> 5691 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[76 (-1413737605528289280)]} 0 0
[junit4:junit4]   2> 5691 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10093 (1413737605527240704)]} 0 1
[junit4:junit4]   2> 5695 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5695 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[76 (-1413737605530386432)]} 0 2
[junit4:junit4]   2> 5696 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5696 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10093 (1413737605530386433)]} 0 3
[junit4:junit4]   2> 5697 T848 C20 P53645 /update {wt=javabin&version=2} {add=[89 (1413737605534580736)]} 0 0
[junit4:junit4]   2> 5699 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10094 (1413737605535629312)]} 0 1
[junit4:junit4]   2> 5701 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5702 T864 C19 P41846 /update {wt=javabin&version=2} {add=[89 (1413737605536677888)]} 0 3
[junit4:junit4]   2> 5703 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5703 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10094 (1413737605537726464)]} 0 3
[junit4:junit4]   2> 5704 T847 C20 P53645 /update {wt=javabin&version=2} {add=[90 (1413737605541920768)]} 0 0
[junit4:junit4]   2> 5705 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10095 (1413737605542969344)]} 0 0
[junit4:junit4]   2> 5708 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5709 T863 C19 P41846 /update {wt=javabin&version=2} {add=[90 (1413737605544017920)]} 0 3
[junit4:junit4]   2> 5711 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[77 (-1413737605549260800)]} 0 1
[junit4:junit4]   2> 5714 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5715 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[77 (-1413737605551357952)]} 0 3
[junit4:junit4]   2> 5715 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 5716 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10095 (1413737605545066496)]} 0 9
[junit4:junit4]   2> 5717 T848 C20 P53645 /update {wt=javabin&version=2} {add=[91 (1413737605555552256)]} 0 1
[junit4:junit4]   2> 5719 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10096 (1413737605556600832)]} 0 1
[junit4:junit4]   2> 5722 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5723 T864 C19 P41846 /update {wt=javabin&version=2} {add=[91 (1413737605557649408)]} 0 4
[junit4:junit4]   2> 5724 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5724 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10096 (1413737605559746560)]} 0 3
[junit4:junit4]   2> 5725 T847 C20 P53645 /update {wt=javabin&version=2} {add=[92 (1413737605563940864)]} 0 1
[junit4:junit4]   2> 5729 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5730 T863 C19 P41846 /update {wt=javabin&version=2} {add=[92 (1413737605566038016)]} 0 3
[junit4:junit4]   2> 5732 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[79 (-1413737605571280896)]} 0 0
[junit4:junit4]   2> 5733 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10097 (1413737605564989440)]} 0 7
[junit4:junit4]   2> 5735 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5736 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[79 (-1413737605573378048)]} 0 3
[junit4:junit4]   2> 5738 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5738 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10097 (1413737605574426624)]} 0 3
[junit4:junit4]   2> 5739 T848 C20 P53645 /update {wt=javabin&version=2} {add=[93 (1413737605577572352)]} 0 1
[junit4:junit4]   2> 5740 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10080 (-1413737605579669504)]} 0 0
[junit4:junit4]   2> 5744 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5745 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10080 (-1413737605581766656)]} 0 3
[junit4:junit4]   2> 5750 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5751 T864 C19 P41846 /update {wt=javabin&version=2} {add=[93 (1413737605580718080)]} 0 10
[junit4:junit4]   2> 5753 T848 C20 P53645 /update {wt=javabin&version=2} {add=[94 (1413737605593300992)]} 0 0
[junit4:junit4]   2> 5754 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10098 (1413737605587009536)]} 0 7
[junit4:junit4]   2> 5758 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5758 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5759 T864 C19 P41846 /update {wt=javabin&version=2} {add=[94 (1413737605595398144)]} 0 4
[junit4:junit4]   2> 5759 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10098 (1413737605596446720)]} 0 3
[junit4:junit4]   2> 5761 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10081 (-1413737605601689601)]} 0 0
[junit4:junit4]   2> 5762 T847 C20 P53645 /update {wt=javabin&version=2} {add=[95 (1413737605601689600)]} 0 1
[junit4:junit4]   2> 5764 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5765 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10081 (-1413737605603786752)]} 0 2
[junit4:junit4]   2> 5766 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5766 T864 C19 P41846 /update {wt=javabin&version=2} {add=[95 (1413737605604835328)]} 0 3
[junit4:junit4]   2> 5767 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10099 (1413737605607981056)]} 0 0
[junit4:junit4]   2> 5768 T848 C20 P53645 /update {wt=javabin&version=2} {add=[96 (1413737605609029632)]} 0 0
[junit4:junit4]   2> 5772 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5772 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10099 (1413737605610078208)]} 0 3
[junit4:junit4]   2> 5774 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10082 (-1413737605615321088)]} 0 0
[junit4:junit4]   2> 5778 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5779 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10082 (-1413737605617418240)]} 0 3
[junit4:junit4]   2> 5779 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 5779 T863 C19 P41846 /update {wt=javabin&version=2} {add=[96 (1413737605611126784)]} 0 9
[junit4:junit4]   2> 5781 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10100 (1413737605622661120)]} 0 0
[junit4:junit4]   2> 5782 T847 C20 P53645 /update {wt=javabin&version=2} {add=[97 (1413737605622661121)]} 0 1
[junit4:junit4]   2> 5786 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5786 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5787 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10100 (1413737605624758272)]} 0 4
[junit4:junit4]   2> 5787 T864 C19 P41846 /update {wt=javabin&version=2} {add=[97 (1413737605624758273)]} 0 4
[junit4:junit4]   2> 5789 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10084 (-1413737605631049728)]} 0 0
[junit4:junit4]   2> 5789 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[80 (-1413737605631049729)]} 0 0
[junit4:junit4]   2> 5792 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5793 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5793 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10084 (-1413737605633146880)]} 0 2
[junit4:junit4]   2> 5793 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[80 (-1413737605633146881)]} 0 2
[junit4:junit4]   2> 5795 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10101 (1413737605637341184)]} 0 0
[junit4:junit4]   2> 5796 T847 C20 P53645 /update {wt=javabin&version=2} {add=[98 (1413737605637341185)]} 0 1
[junit4:junit4]   2> 5800 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5800 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5800 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10101 (1413737605639438336)]} 0 3
[junit4:junit4]   2> 5800 T864 C19 P41846 /update {wt=javabin&version=2} {add=[98 (1413737605639438337)]} 0 3
[junit4:junit4]   2> 5802 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10085 (-1413737605644681216)]} 0 0
[junit4:junit4]   2> 5802 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[81 (-1413737605644681217)]} 0 0
[junit4:junit4]   2> 5806 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5806 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5806 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10085 (-1413737605646778368)]} 0 2
[junit4:junit4]   2> 5806 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[81 (-1413737605646778369)]} 0 2
[junit4:junit4]   2> 5809 T847 C20 P53645 /update {wt=javabin&version=2} {add=[99 (1413737605650972673)]} 0 1
[junit4:junit4]   2> 5809 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10102 (1413737605650972672)]} 0 1
[junit4:junit4]   2> 5818 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5818 T863 C19 P41846 /update {wt=javabin&version=2} {add=[99 (1413737605657264128)]} 0 7
[junit4:junit4]   2> 5821 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[82 (-1413737605664604160)]} 0 0
[junit4:junit4]   2> 5825 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5825 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[82 (-1413737605666701312)]} 0 2
[junit4:junit4]   2> 5827 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5828 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10102 (1413737605654118400)]} 0 17
[junit4:junit4]   2> 5828 T848 C20 P53645 /update {wt=javabin&version=2} {add=[100 (1413737605670895616)]} 0 1
[junit4:junit4]   2> 5830 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10088 (-1413737605674041344)]} 0 0
[junit4:junit4]   2> 5833 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5833 T864 C19 P41846 /update {wt=javabin&version=2} {add=[100 (1413737605674041344)]} 0 3
[junit4:junit4]   2> 5834 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5834 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10088 (-1413737605676138496)]} 0 2
[junit4:junit4]   2> 5835 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[84 (-1413737605679284224)]} 0 0
[junit4:junit4]   2> 5836 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10103 (1413737605680332800)]} 0 0
[junit4:junit4]   2> 5840 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5840 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[84 (-1413737605682429952)]} 0 3
[junit4:junit4]   2> 5841 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5841 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10103 (1413737605682429953)]} 0 3
[junit4:junit4]   2> 5842 T848 C20 P53645 /update {wt=javabin&version=2} {add=[101 (1413737605686624256)]} 0 0
[junit4:junit4]   2> 5843 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10089 (-1413737605687672832)]} 0 0
[junit4:junit4]   2> 5847 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5847 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5848 T864 C19 P41846 /update {wt=javabin&version=2} {add=[101 (1413737605688721408)]} 0 4
[junit4:junit4]   2> 5848 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10089 (-1413737605689769984)]} 0 3
[junit4:junit4]   2> 5851 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[85 (-1413737605695012864)]} 0 1
[junit4:junit4]   2> 5855 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5856 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[85 (-1413737605698158592)]} 0 3
[junit4:junit4]   2> 5859 T847 C20 P53645 /update {wt=javabin&version=2} {add=[102 (1413737605704450048)]} 0 1
[junit4:junit4]   2> 5864 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10104 (1413737605696061440)]} 0 13
[junit4:junit4]   2> 5872 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 5873 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10104 (1413737605712838656)]} 0 7
[junit4:junit4]   2> 5878 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10105 (1413737605722275840)]} 0 2
[junit4:junit4]   2> 5883 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=19 
[junit4:junit4]   2> 5884 T863 C19 P41846 /update {wt=javabin&version=2} {add=[102 (1413737605707595776)]} 0 22
[junit4:junit4]   2> 5885 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5886 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10105 (1413737605727518720)]} 0 4
[junit4:junit4]   2> 5886 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[88 (-1413737605732761600)]} 0 0
[junit4:junit4]   2> 5887 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10091 (-1413737605733810176)]} 0 0
[junit4:junit4]   2> 5891 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5891 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[88 (-1413737605734858752)]} 0 3
[junit4:junit4]   2> 5891 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5892 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10091 (-1413737605735907328)]} 0 3
[junit4:junit4]   2> 5894 T847 C20 P53645 /update {wt=javabin&version=2} {add=[103 (1413737605741150208)]} 0 1
[junit4:junit4]   2> 5894 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10106 (1413737605741150209)]} 0 0
[junit4:junit4]   2> 5899 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5900 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5900 T863 C19 P41846 /update {wt=javabin&version=2} {add=[103 (1413737605743247360)]} 0 4
[junit4:junit4]   2> 5900 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10106 (1413737605744295936)]} 0 4
[junit4:junit4]   2> 5902 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[89 (-1413737605749538816)]} 0 0
[junit4:junit4]   2> 5903 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10107 (1413737605749538817)]} 0 1
[junit4:junit4]   2> 5907 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5907 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[89 (-1413737605751635968)]} 0 3
[junit4:junit4]   2> 5911 T847 C20 P53645 /update {wt=javabin&version=2} {add=[104 (1413737605757927424)]} 0 1
[junit4:junit4]   2> 5919 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5920 T864 C19 P41846 /update {wt=javabin&version=2} {add=[104 (1413737605762121728)]} 0 6
[junit4:junit4]   2> 5923 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5924 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10107 (1413737605752684544)]} 0 19
[junit4:junit4]   2> 5927 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10096 (-1413737605775753216)]} 0 0
[junit4:junit4]   2> 5932 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5933 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10096 (-1413737605778898944)]} 0 3
[junit4:junit4]   2> 5936 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10108 (1413737605784141824)]} 0 1
[junit4:junit4]   2> 5939 T847 C20 P53645 /update {wt=javabin&version=2} {add=[105 (1413737605771558912)]} 0 17
[junit4:junit4]   2> 5943 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5943 T863 C19 P41846 /update {wt=javabin&version=2} {add=[10108 (1413737605788336128)]} 0 4
[junit4:junit4]   2> 5945 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5946 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[10098 (-1413737605795676160)]} 0 0
[junit4:junit4]   2> 5946 T864 C19 P41846 /update {wt=javabin&version=2} {add=[105 (1413737605791481856)]} 0 5
[junit4:junit4]   2> 5950 T847 C20 P53645 /update {wt=javabin&version=2} {delete=[91 (-1413737605799870464)]} 0 1
[junit4:junit4]   2> 5952 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5953 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[10098 (-1413737605798821888)]} 0 4
[junit4:junit4]   2> 5955 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5956 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[91 (-1413737605801967616)]} 0 4
[junit4:junit4]   2> 5958 T847 C20 P53645 /update {wt=javabin&version=2} {add=[10109 (1413737605805113344)]} 0 3
[junit4:junit4]   2> 5959 T848 C20 P53645 /update {wt=javabin&version=2} {add=[106 (1413737605808259072)]} 0 1
[junit4:junit4]   2> 5964 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5965 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10109 (1413737605810356224)]} 0 5
[junit4:junit4]   2> 5968 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10100 (-1413737605817696256)]} 0 1
[junit4:junit4]   2> 5973 T873 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5974 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10100 (-1413737605820841984)]} 0 3
[junit4:junit4]   2> 5977 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10110 (1413737605827133440)]} 0 1
[junit4:junit4]   2> 5978 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=13 
[junit4:junit4]   2> 5979 T863 C19 P41846 /update {wt=javabin&version=2} {add=[106 (1413737605812453376)]} 0 17
[junit4:junit4]   2> 5983 T848 C20 P53645 /update {wt=javabin&version=2} {add=[107 (1413737605833424896)]} 0 1
[junit4:junit4]   2> 5985 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 5985 T864 C19 P41846 /update {wt=javabin&version=2} {add=[10110 (1413737605831327744)]} 0 5
[junit4:junit4]   2> 5987 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[10101 (-1413737605838667776)]} 0 0
[junit4:junit4]   2> 5988 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5988 T863 C19 P41846 /update {wt=javabin&version=2} {add=[107 (1413737605836570624)]} 0 3
[junit4:junit4]   2> 5990 T848 C20 P53645 /update {wt=javabin&version=2} {delete=[96 (-1413737605841813504)]} 0 0
[junit4:junit4]   2> 5991 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5991 T864 C19 P41846 /update {wt=javabin&version=2} {delete=[10101 (-1413737605840764928)]} 0 2
[junit4:junit4]   2> 5994 T848 C20 P53645 /update {wt=javabin&version=2} {add=[10111 (1413737605844959232)]} 0 1
[junit4:junit4]   2> 5994 T874 C18 P33194 REQ /update {distrib.from=http://127.0.0.1:41846/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5994 T863 C19 P41846 /update {wt=javabin&version=2} {delete=[96 (-1413737605843910656)]} 0 2
[junit4:junit4]   2> 5996 T848 C20 P53645 /update {wt=javabin&version=2} {add=[108 (1413737605848104960)]} 0 0
[junit4:junit4]   2> 5998 T874 C18 P33194 REQ /update {distrib.from=http://127.0.

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

ck_0.tii, _bc_MockSep_0.pos, _bc.si, _bc_MockVariableIntBlock_0.tib, _bc_nrm.cfe, _bc_MockSep_0.skp, _bc_MockVariableIntBlock_0.pos, _bc_nrm.cfs, _bc_MockFixedIntBlock_0.doc, _bc_MockVariableIntBlock_0.pyl, _bc_MockFixedIntBlock_0.tib, _bc_MockFixedIntBlock_0.pyl, _bc_MockSep_0.tib, _bc_MockSep_0.doc, _bc_MockFixedIntBlock_0.frq, _bc_MockSep_0.tii, _bc_MockSep_0.pyl, segments_4]
[junit4:junit4]   2> 27668 T835 C19 P41846 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 27697 T835 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 28412 T868 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:32944
[junit4:junit4]   2> 28513 T869 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 28513 T835 oaz.ZooKeeper.close Session: 0x139e9adb43d0003 closed
[junit4:junit4]   2> 28515 T835 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 28566 T835 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 33194
[junit4:junit4]   2> 28567 T835 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=484462
[junit4:junit4]   2> 29177 T1007 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:32944
[junit4:junit4]   2> 29278 T1008 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 29278 T835 oaz.ZooKeeper.close Session: 0x139e9adb43d0007 closed
[junit4:junit4]   2> 29280 T835 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 29379 T835 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=64CD9B636822BEAB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_NI -Dtests.timezone=America/Merida -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   29.4s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:33194/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([64CD9B636822BEAB:E52B157B1F7DDE97]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
[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:324)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 29415 T835 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {range_facet_l=MockFixedIntBlock(blockSize=1181), text=PostingsFormat(name=MockSep), _version_=MockFixedIntBlock(blockSize=1181), rnd_b=MockVariableIntBlock(baseBlockSize=90), intDefault=MockFixedIntBlock(blockSize=1181), timestamp=MockFixedIntBlock(blockSize=1181), id=MockVariableIntBlock(baseBlockSize=90), a_t=MockFixedIntBlock(blockSize=1181), range_facet_sl=PostingsFormat(name=MockSep), range_facet_si=PostingsFormat(name=SimpleText), other_tl1=MockFixedIntBlock(blockSize=1181), multiDefault=PostingsFormat(name=SimpleText), a_si=PostingsFormat(name=SimpleText)}, sim=DefaultSimilarity, locale=es_NI, timezone=America/Merida
[junit4:junit4]   2> NOTE: Linux 3.2.0-30-generic i386/Oracle Corporation 1.7.0_07 (32-bit)/cpus=8,threads=1,free=125261080,total=245366784
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestPropInjectDefaults, TestFoldingMultitermQuery, OverseerTest, SyncSliceTest, ClusterStateUpdateTest, RequiredFieldsTest, ReturnFieldsTest, TestTrie, BasicDistributedZk2Test, TestRTGBase, PrimUtilsTest, TestPropInject, DOMUtilTest, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 29.44s, 1 test, 1 error <<< FAILURES!

[...truncated 580 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:32: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:179: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/module-build.xml:63: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1140: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:813: There were test failures: 231 suites, 954 tests, 1 error, 7 ignored (2 assumptions)

Total time: 22 minutes 24 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.7.0_07 -server -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message