lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk6) - Build # 3091 - Failure!
Date Thu, 06 Dec 2012 17:48:15 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3091/
Java: 64bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest

Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1315, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.

Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1315, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	at __randomizedtesting.SeedInfo.seed([FFCE214293B40E5F]:0)
	at java.lang.Thread.getStackTraceImpl(Native Method)
	at java.lang.Thread.getStackTrace(Thread.java:1047)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:515)
	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:981)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:873)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:865)
	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:232)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:192)
	at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:374)
	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:273)
	at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:152)
	at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:407)
	at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:222)
Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index6724167273tmp lockFactory=org.apache.lucene.store.SimpleFSLockFactory@5610561; maxCacheMB=0.0810546875 maxMergeSizeMB=0.2421875))
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:738)


REGRESSION:  org.apache.solr.cloud.SyncSliceTest.testDistribSearch

Error Message:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt.bak (No such file or directory)

Stack Trace:
java.io.FileNotFoundException: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt.bak (No such file or directory)
	at __randomizedtesting.SeedInfo.seed([FFCE214293B40E5F:7E28AF5AE4EB6E63]:0)
	at java.io.FileInputStream.open(Native Method)
	at java.io.FileInputStream.<init>(FileInputStream.java:137)
	at org.apache.commons.io.FileUtils.doCopyFile(FileUtils.java:935)
	at org.apache.commons.io.FileUtils.doCopyDirectory(FileUtils.java:1225)
	at org.apache.commons.io.FileUtils.doCopyDirectory(FileUtils.java:1223)
	at org.apache.commons.io.FileUtils.doCopyDirectory(FileUtils.java:1223)
	at org.apache.commons.io.FileUtils.copyDirectory(FileUtils.java:1186)
	at org.apache.commons.io.FileUtils.copyDirectory(FileUtils.java:1058)
	at org.apache.commons.io.FileUtils.copyDirectory(FileUtils.java:1027)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:236)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:693)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
	at java.lang.reflect.Method.invoke(Method.java:611)
	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:738)




Build Log:
[...truncated 8101 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 3 T1262 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1354815240055
[junit4:junit4]   2> 4 T1262 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 5 T1263 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 7 T1263 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 8 T1263 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 8 T1263 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 9 T1263 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 10 T1263 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 105 T1262 oasc.ZkTestServer.run start zk server on port:46382
[junit4:junit4]   2> 106 T1262 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46382 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1bee1bee
[junit4:junit4]   2> 107 T1262 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 107 T1268 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:46382. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 108 T1268 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46382, initiating session
[junit4:junit4]   2> 108 T1264 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45684
[junit4:junit4]   2> 109 T1264 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45684
[junit4:junit4]   2> 110 T1266 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 249 T1266 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b7147f77e0000 with negotiated timeout 10000 for client /127.0.0.1:45684
[junit4:junit4]   2> 250 T1268 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:46382, sessionid = 0x13b7147f77e0000, negotiated timeout = 10000
[junit4:junit4]   2> 250 T1269 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1bee1bee name:ZooKeeperConnection Watcher:127.0.0.1:46382 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 251 T1262 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 251 T1262 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 255 T1267 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b7147f77e0000
[junit4:junit4]   2> 256 T1269 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 256 T1264 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45684 which had sessionid 0x13b7147f77e0000
[junit4:junit4]   2> 256 T1262 oaz.ZooKeeper.close Session: 0x13b7147f77e0000 closed
[junit4:junit4]   2> 257 T1262 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46382/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@250e250e
[junit4:junit4]   2> 258 T1262 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 258 T1270 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:46382. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 259 T1270 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46382, initiating session
[junit4:junit4]   2> 259 T1264 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45685
[junit4:junit4]   2> 260 T1264 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45685
[junit4:junit4]   2> 261 T1266 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b7147f77e0001 with negotiated timeout 10000 for client /127.0.0.1:45685
[junit4:junit4]   2> 261 T1270 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:46382, sessionid = 0x13b7147f77e0001, negotiated timeout = 10000
[junit4:junit4]   2> 261 T1271 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@250e250e name:ZooKeeperConnection Watcher:127.0.0.1:46382/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 262 T1262 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 262 T1262 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 266 T1262 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 270 T1262 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 273 T1262 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 276 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 277 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 283 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 285 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 290 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 292 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 296 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 297 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 301 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 302 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 306 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 307 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 312 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 313 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 317 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 318 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 331 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 332 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 336 T1262 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 337 T1262 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 342 T1267 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b7147f77e0001
[junit4:junit4]   2> 343 T1271 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 343 T1264 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45685 which had sessionid 0x13b7147f77e0001
[junit4:junit4]   2> 343 T1262 oaz.ZooKeeper.close Session: 0x13b7147f77e0001 closed
[junit4:junit4]   2> 478 T1262 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 480 T1262 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:37805
[junit4:junit4]   2> 481 T1262 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 482 T1262 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395
[junit4:junit4]   2> 483 T1262 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/'
[junit4:junit4]   2> 493 T1262 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 494 T1262 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 494 T1262 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395
[junit4:junit4]   2> 495 T1262 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/solr.xml
[junit4:junit4]   2> 495 T1262 oasc.CoreContainer.<init> New CoreContainer 1400918912
[junit4:junit4]   2> 496 T1262 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/'
[junit4:junit4]   2> 497 T1262 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/'
[junit4:junit4]   2> 510 T1262 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 520 T1262 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:46382/solr
[junit4:junit4]   2> 521 T1262 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46382/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@7e6c7e6c
[junit4:junit4]   2> 522 T1262 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 524 T1281 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:46382. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 525 T1281 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46382, initiating session
[junit4:junit4]   2> 525 T1264 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45686
[junit4:junit4]   2> 526 T1264 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45686
[junit4:junit4]   2> 528 T1266 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b7147f77e0002 with negotiated timeout 8000 for client /127.0.0.1:45686
[junit4:junit4]   2> 528 T1281 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:46382, sessionid = 0x13b7147f77e0002, negotiated timeout = 8000
[junit4:junit4]   2> 529 T1282 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e6c7e6c name:ZooKeeperConnection Watcher:127.0.0.1:46382/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 530 T1262 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 532 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x2 zxid:0x19 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 534 T1262 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 538 T1262 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37805_solr
[junit4:junit4]   2> 540 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:delete cxid:0x7 zxid:0x1b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37805_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37805_solr
[junit4:junit4]   2> 542 T1262 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37805_solr
[junit4:junit4]   2> 549 T1262 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 550 T1262 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 551 T1262 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 551 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 552 T1262 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 552 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 553 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 553 T1262 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 554 T1262 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 554 T1262 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 560 T1262 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 570 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:delete cxid:0x15 zxid:0x20 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 572 T1262 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 577 T1262 oasc.Overseer.start Overseer (id=88789171572703234-127.0.0.1:37805_solr-n_0000000000) starting
[junit4:junit4]   2> 578 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x1a zxid:0x22 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 580 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 581 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 583 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 585 T1284 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 585 T1262 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 591 T1262 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 595 T1283 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 600 T1285 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/collection1
[junit4:junit4]   2> 601 T1285 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 602 T1285 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 602 T1285 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 604 T1285 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/collection1/'
[junit4:junit4]   2> 605 T1285 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/collection1/lib/README' to classloader
[junit4:junit4]   2> 606 T1285 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 636 T1285 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 690 T1285 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 711 T1285 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 716 T1285 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1075 T1285 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1081 T1285 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1086 T1285 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1107 T1285 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1111 T1285 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1116 T1285 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1118 T1285 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1120 T1285 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1354815240395/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data/
[junit4:junit4]   2> 1120 T1285 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a192a19
[junit4:junit4]   2> 1121 T1285 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 1123 T1285 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index6724167270tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@54b154b1; maxCacheMB=0.0810546875 maxMergeSizeMB=0.2421875)) assuming 'simple'
[junit4:junit4]   2> 1123 T1285 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data forceNew:false
[junit4:junit4]   2> 1124 T1285 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data
[junit4:junit4]   2> 1124 T1285 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data/index/
[junit4:junit4]   2> 1125 T1285 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1125 T1285 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data/index forceNew:false
[junit4:junit4]   2> 1146 T1285 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4c4a4c4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@55385538),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1147 T1285 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1147 T1285 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data/index
[junit4:junit4]   2> 1148 T1285 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1150 T1285 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1150 T1285 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1151 T1285 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1151 T1285 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1152 T1285 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1152 T1285 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1153 T1285 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1154 T1285 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1155 T1285 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1157 T1285 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1158 T1285 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1159 T1285 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1160 T1285 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1161 T1285 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1161 T1285 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1163 T1285 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1164 T1285 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1165 T1285 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1167 T1285 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1168 T1285 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1169 T1285 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1171 T1285 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1172 T1285 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1173 T1285 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1175 T1285 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1176 T1285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1177 T1285 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1180 T1285 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1182 T1285 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data
[junit4:junit4]   2> 1182 T1285 oass.SolrIndexSearcher.<init> Opening Searcher@44844484 main
[junit4:junit4]   2> 1183 T1285 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1184 T1285 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1184 T1285 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1203 T1285 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1249 T1286 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44844484 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1258 T1285 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1259 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x53 zxid:0x28 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1605 T1283 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1606 T1283 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37805/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37805_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 1607 T1283 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 1607 T1283 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 1608 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x5b zxid:0x2b txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1645 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1762 T1285 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 1763 T1285 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:37805/solr shardId:shard1
[junit4:junit4]   2> 1764 T1285 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 1776 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:delete cxid:0x75 zxid:0x35 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1776 T1285 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1777 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x76 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1810 T1285 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1811 T1285 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1812 T1285 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:37805/solr/collection1/
[junit4:junit4]   2> 1812 T1285 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1813 T1285 oasc.SyncStrategy.syncToMe http://127.0.0.1:37805/solr/collection1/ has no replicas
[junit4:junit4]   2> 1813 T1285 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:37805/solr/collection1/
[junit4:junit4]   2> 1814 T1285 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 1820 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0002 type:create cxid:0x80 zxid:0x3a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2151 T1283 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2170 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2223 T1285 oasc.ZkController.register We are http://127.0.0.1:37805/solr/collection1/ and leader is http://127.0.0.1:37805/solr/collection1/
[junit4:junit4]   2> 2223 T1285 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37805/solr
[junit4:junit4]   2> 2224 T1285 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2224 T1285 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2284 T1285 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2285 T1262 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 2286 T1262 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2287 T1262 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2290 T1262 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 2294 T1262 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46382/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@56315631
[junit4:junit4]   2> 2295 T1262 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2295 T1287 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:46382. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 2296 T1287 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46382, initiating session
[junit4:junit4]   2> 2296 T1264 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45687
[junit4:junit4]   2> 2296 T1264 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45687
[junit4:junit4]   2> 2300 T1266 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b7147f77e0003 with negotiated timeout 10000 for client /127.0.0.1:45687
[junit4:junit4]   2> 2300 T1287 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:46382, sessionid = 0x13b7147f77e0003, negotiated timeout = 10000
[junit4:junit4]   2> 2301 T1288 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@56315631 name:ZooKeeperConnection Watcher:127.0.0.1:46382/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2301 T1262 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2302 T1262 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2305 T1262 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 2396 T1262 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 2398 T1262 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:44186
[junit4:junit4]   2> 2399 T1262 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2400 T1262 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356
[junit4:junit4]   2> 2400 T1262 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/'
[junit4:junit4]   2> 2411 T1262 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2412 T1262 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2412 T1262 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356
[junit4:junit4]   2> 2413 T1262 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/solr.xml
[junit4:junit4]   2> 2413 T1262 oasc.CoreContainer.<init> New CoreContainer 1492867323
[junit4:junit4]   2> 2414 T1262 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/'
[junit4:junit4]   2> 2414 T1262 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/'
[junit4:junit4]   2> 2427 T1262 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2436 T1262 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:46382/solr
[junit4:junit4]   2> 2437 T1262 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46382/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@25892589
[junit4:junit4]   2> 2438 T1262 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2439 T1298 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:46382. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 2439 T1298 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46382, initiating session
[junit4:junit4]   2> 2440 T1264 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45688
[junit4:junit4]   2> 2440 T1264 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45688
[junit4:junit4]   2> 2442 T1266 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b7147f77e0004 with negotiated timeout 8000 for client /127.0.0.1:45688
[junit4:junit4]   2> 2442 T1298 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:46382, sessionid = 0x13b7147f77e0004, negotiated timeout = 8000
[junit4:junit4]   2> 2443 T1299 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@25892589 name:ZooKeeperConnection Watcher:127.0.0.1:46382/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2443 T1262 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2444 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0004 type:create cxid:0x1 zxid:0x46 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2445 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0004 type:create cxid:0x2 zxid:0x47 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2447 T1262 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44186_solr
[junit4:junit4]   2> 2448 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0004 type:delete cxid:0x4 zxid:0x48 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:44186_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:44186_solr
[junit4:junit4]   2> 2449 T1262 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44186_solr
[junit4:junit4]   2> 2451 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2452 T1288 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2453 T1262 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2454 T1262 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2454 T1282 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2454 T1262 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2455 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2455 T1262 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2456 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2456 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2457 T1262 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2457 T1262 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2458 T1262 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2466 T1262 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2473 T1300 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/collection1
[junit4:junit4]   2> 2473 T1300 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2474 T1300 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2475 T1300 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2477 T1300 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/collection1/'
[junit4:junit4]   2> 2478 T1300 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/collection1/lib/README' to classloader
[junit4:junit4]   2> 2478 T1300 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2526 T1300 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2584 T1300 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2586 T1300 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2590 T1300 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2681 T1283 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2683 T1283 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37805/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37805_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 2689 T1288 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2689 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2689 T1299 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3004 T1300 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3009 T1300 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3012 T1300 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3029 T1300 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3035 T1300 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3040 T1300 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3042 T1300 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3044 T1300 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1354815242356/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1/
[junit4:junit4]   2> 3045 T1300 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a192a19
[junit4:junit4]   2> 3046 T1300 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 3048 T1300 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index6724167271tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@595b595b; maxCacheMB=0.0810546875 maxMergeSizeMB=0.2421875)) assuming 'simple'
[junit4:junit4]   2> 3049 T1300 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1 forceNew:false
[junit4:junit4]   2> 3050 T1300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1
[junit4:junit4]   2> 3051 T1300 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1/index/
[junit4:junit4]   2> 3051 T1300 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3052 T1300 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1/index forceNew:false
[junit4:junit4]   2> 3055 T1300 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4bbb4bbb lockFactory=org.apache.lucene.store.NativeFSLockFactory@54a954a9),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3056 T1300 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 3057 T1300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1/index
[junit4:junit4]   2> 3057 T1300 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3061 T1300 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3061 T1300 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3062 T1300 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3063 T1300 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3063 T1300 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3064 T1300 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3065 T1300 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3067 T1300 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3070 T1300 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3072 T1300 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3074 T1300 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3075 T1300 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3078 T1300 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3078 T1300 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3079 T1300 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3082 T1300 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3084 T1300 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3086 T1300 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3089 T1300 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3091 T1300 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3093 T1300 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3095 T1300 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3098 T1300 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3100 T1300 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3102 T1300 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3104 T1300 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3106 T1300 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3110 T1300 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3112 T1300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1
[junit4:junit4]   2> 3113 T1300 oass.SolrIndexSearcher.<init> Opening Searcher@29762976 main
[junit4:junit4]   2> 3126 T1300 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3127 T1300 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3128 T1300 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3274 T1300 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3323 T1301 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29762976 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3352 T1300 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3749 T1283 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3751 T1283 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44186/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44186_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 3751 T1283 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 3752 T1283 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 3762 T1299 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3762 T1288 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3762 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3854 T1300 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 3855 T1300 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:44186/solr shardId:shard1
[junit4:junit4]   2> 3856 T1300 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3866 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0004 type:delete cxid:0x45 zxid:0x58 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3868 T1300 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3868 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0004 type:create cxid:0x46 zxid:0x59 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3872 T1300 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3872 T1300 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3873 T1300 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:44186/solr/collection1/
[junit4:junit4]   2> 3873 T1300 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3874 T1300 oasc.SyncStrategy.syncToMe http://127.0.0.1:44186/solr/collection1/ has no replicas
[junit4:junit4]   2> 3874 T1300 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:44186/solr/collection1/
[junit4:junit4]   2> 3874 T1300 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3881 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0004 type:create cxid:0x50 zxid:0x5d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4272 T1283 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4389 T1288 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4389 T1299 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4389 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4434 T1300 oasc.ZkController.register We are http://127.0.0.1:44186/solr/collection1/ and leader is http://127.0.0.1:44186/solr/collection1/
[junit4:junit4]   2> 4435 T1300 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:44186/solr
[junit4:junit4]   2> 4435 T1300 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4436 T1300 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4438 T1300 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4440 T1262 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4440 T1262 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4441 T1262 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4553 T1262 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 4555 T1262 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55429
[junit4:junit4]   2> 4556 T1262 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4556 T1262 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495
[junit4:junit4]   2> 4557 T1262 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/'
[junit4:junit4]   2> 4567 T1262 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4568 T1262 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4568 T1262 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495
[junit4:junit4]   2> 4569 T1262 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/solr.xml
[junit4:junit4]   2> 4569 T1262 oasc.CoreContainer.<init> New CoreContainer 822685961
[junit4:junit4]   2> 4570 T1262 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/'
[junit4:junit4]   2> 4571 T1262 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/'
[junit4:junit4]   2> 4583 T1262 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4592 T1262 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:46382/solr
[junit4:junit4]   2> 4593 T1262 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:46382/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@2d692d69
[junit4:junit4]   2> 4594 T1262 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4594 T1311 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:46382. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 4595 T1311 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:46382, initiating session
[junit4:junit4]   2> 4595 T1264 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45690
[junit4:junit4]   2> 4596 T1264 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45690
[junit4:junit4]   2> 4598 T1266 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b7147f77e0005 with negotiated timeout 8000 for client /127.0.0.1:45690
[junit4:junit4]   2> 4598 T1311 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:46382, sessionid = 0x13b7147f77e0005, negotiated timeout = 8000
[junit4:junit4]   2> 4599 T1312 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2d692d69 name:ZooKeeperConnection Watcher:127.0.0.1:46382/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4599 T1262 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4600 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0005 type:create cxid:0x1 zxid:0x68 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4602 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0005 type:create cxid:0x2 zxid:0x69 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4604 T1262 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55429_solr
[junit4:junit4]   2> 4605 T1267 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b7147f77e0005 type:delete cxid:0x4 zxid:0x6a txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55429_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55429_solr
[junit4:junit4]   2> 4606 T1262 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55429_solr
[junit4:junit4]   2> 4609 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4610 T1288 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4610 T1288 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4611 T1299 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4611 T1262 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4612 T1299 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4612 T1282 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4612 T1262 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4613 T1262 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4614 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4615 T1262 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4615 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4616 T1262 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4616 T1262 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4617 T1262 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4617 T1262 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4625 T1262 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4632 T1313 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/collection1
[junit4:junit4]   2> 4633 T1313 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4634 T1313 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4634 T1313 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4636 T1313 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/collection1/'
[junit4:junit4]   2> 4637 T1313 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/collection1/lib/README' to classloader
[junit4:junit4]   2> 4637 T1313 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4665 T1313 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4703 T1313 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4705 T1313 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4709 T1313 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4900 T1283 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4902 T1283 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44186/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44186_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 4909 T1288 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4909 T1299 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4910 T1312 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4930 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5116 T1313 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5121 T1313 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5147 T1313 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5166 T1313 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5171 T1313 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5175 T1313 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5177 T1313 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5179 T1313 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1354815244495/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2/
[junit4:junit4]   2> 5179 T1313 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a192a19
[junit4:junit4]   2> 5180 T1313 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5182 T1313 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index6724167272tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@4f304f30; maxCacheMB=0.0810546875 maxMergeSizeMB=0.2421875)) assuming 'simple'
[junit4:junit4]   2> 5182 T1313 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2 forceNew:false
[junit4:junit4]   2> 5183 T1313 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2
[junit4:junit4]   2> 5183 T1313 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2/index/
[junit4:junit4]   2> 5184 T1313 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5184 T1313 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2/index forceNew:false
[junit4:junit4]   2> 5186 T1313 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@42c042c0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@4bae4bae),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5186 T1313 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5187 T1313 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2/index
[junit4:junit4]   2> 5187 T1313 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5190 T1313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5190 T1313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5191 T1313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5191 T1313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5192 T1313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5192 T1313 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5193 T1313 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5194 T1313 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5196 T1313 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5197 T1313 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5199 T1313 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5199 T1313 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5201 T1313 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5201 T1313 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5202 T1313 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5203 T1313 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5205 T1313 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5206 T1313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5208 T1313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5209 T1313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5212 T1313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5213 T1313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5215 T1313 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5216 T1313 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5218 T1313 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5219 T1313 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5220 T1313 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5223 T1313 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 5225 T1313 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2
[junit4:junit4]   2> 5225 T1313 oass.SolrIndexSearcher.<init> Opening Searcher@586f586f main
[junit4:junit4]   2> 5226 T1313 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5226 T1313 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5227 T1313 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5242 T1313 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5296 T1314 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@586f586f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5309 T1313 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5463 T1283 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5464 T1283 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55429/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55429_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5465 T1283 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 5466 T1283 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 5479 T1312 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5479 T1299 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5480 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5479 T1288 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5812 T1313 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 5812 T1313 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55429/solr shardId:shard1
[junit4:junit4]   2> 5817 T1313 oasc.ZkController.register We are http://127.0.0.1:55429/solr/collection1/ and leader is http://127.0.0.1:44186/solr/collection1/
[junit4:junit4]   2> 5818 T1313 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55429/solr
[junit4:junit4]   2> 5818 T1313 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 5819 T1313 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C113 name=collection1 org.apache.solr.core.SolrCore@1af01af0 url=http://127.0.0.1:55429/solr/collection1 node=127.0.0.1:55429_solr
[junit4:junit4]   2> 5819 T1315 C113 P55429 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 5820 T1315 C113 P55429 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 5820 T1313 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5820 T1315 C113 P55429 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5822 T1262 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 5822 T1315 C113 P55429 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:44186/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 5823 T1262 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5823 T1315 C113 P55429 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55429/solr START replicas=[http://127.0.0.1:44186/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 5825 T1315 C113 P55429 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 5825 T1262 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5825 T1315 C113 P55429 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 5826 T1315 C113 P55429 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 5826 T1315 C113 P55429 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C114 name=collection1 org.apache.solr.core.SolrCore@5a485a48 url=http://127.0.0.1:44186/solr/collection1 node=127.0.0.1:44186_solr
[junit4:junit4]   2> 5830 T1296 C114 P44186 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 5832 T1262 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C115 name=collection1 org.apache.solr.core.SolrCore@70eb70eb url=http://127.0.0.1:37805/solr/collection1 node=127.0.0.1:37805_solr
[junit4:junit4]   2> 5838 T1279 C115 P37805 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/control/data
[junit4:junit4]   2> 5840 T1279 C115 P37805 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4c4a4c4a lockFactory=org.apache.lucene.store.NativeFSLockFactory@55385538),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5841 T1279 C115 P37805 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5843 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[1 (1420626751273304064)]} 0 5
[junit4:junit4]   2> 5843 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10001 (1420626751274352640)]} 0 4
[junit4:junit4]   2> 5845 T1295 C114 P44186 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty1
[junit4:junit4]   2> 5847 T1295 C114 P44186 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4bbb4bbb lockFactory=org.apache.lucene.store.NativeFSLockFactory@54a954a9),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5848 T1295 C114 P44186 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5850 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[1 (1420626751280644096)]} 0 5
[junit4:junit4]   2> 5850 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10001 (1420626751280644097)]} 0 5
[junit4:junit4]   2> 5854 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[2 (1420626751287984128)]} 0 2
[junit4:junit4]   2> 5854 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10002 (1420626751287984129)]} 0 2
[junit4:junit4]   2> 5857 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[2 (1420626751292178432)]} 0 1
[junit4:junit4]   2> 5857 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10002 (1420626751292178433)]} 0 1
[junit4:junit4]   2> 5860 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[3 (1420626751295324160)]} 0 1
[junit4:junit4]   2> 5860 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10003 (1420626751296372736)]} 0 1
[junit4:junit4]   2> 5863 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[3 (1420626751298469888)]} 0 1
[junit4:junit4]   2> 5864 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10003 (1420626751299518464)]} 0 1
[junit4:junit4]   2> 5866 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[4 (1420626751301615616)]} 0 1
[junit4:junit4]   2> 5866 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10004 (1420626751302664192)]} 0 0
[junit4:junit4]   2> 5869 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[4 (1420626751304761344)]} 0 1
[junit4:junit4]   2> 5869 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10004 (1420626751305809920)]} 0 0
[junit4:junit4]   2> 5872 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[5 (1420626751307907072)]} 0 1
[junit4:junit4]   2> 5873 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10005 (1420626751308955648)]} 0 1
[junit4:junit4]   2> 5875 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[5 (1420626751311052800)]} 0 1
[junit4:junit4]   2> 5875 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10005 (1420626751312101376)]} 0 0
[junit4:junit4]   2> 5877 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[5 (-1420626751314198528)]} 0 0
[junit4:junit4]   2> 5878 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[10005 (-1420626751315247104)]} 0 0
[junit4:junit4]   2> 5880 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[5 (-1420626751317344256)]} 0 1
[junit4:junit4]   2> 5880 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10005 (-1420626751317344257)]} 0 0
[junit4:junit4]   2> 5883 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[6 (1420626751319441408)]} 0 1
[junit4:junit4]   2> 5883 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10006 (1420626751319441409)]} 0 1
[junit4:junit4]   2> 5886 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[6 (1420626751322587136)]} 0 1
[junit4:junit4]   2> 5886 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10006 (1420626751323635712)]} 0 0
[junit4:junit4]   2> 5889 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[7 (1420626751325732864)]} 0 1
[junit4:junit4]   2> 5890 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10007 (1420626751326781440)]} 0 2
[junit4:junit4]   2> 5892 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[7 (1420626751328878592)]} 0 1
[junit4:junit4]   2> 5894 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10007 (1420626751330975744)]} 0 1
[junit4:junit4]   2> 5896 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[8 (1420626751333072896)]} 0 1
[junit4:junit4]   2> 5899 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10008 (1420626751336218624)]} 0 1
[junit4:junit4]   2> 5900 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[8 (1420626751338315776)]} 0 0
[junit4:junit4]   2> 5902 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10008 (1420626751339364352)]} 0 1
[junit4:junit4]   2> 5903 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[9 (1420626751341461504)]} 0 0
[junit4:junit4]   2> 5904 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10009 (1420626751342510080)]} 0 0
[junit4:junit4]   2> 5906 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[9 (1420626751343558656)]} 0 1
[junit4:junit4]   2> 5907 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10009 (1420626751344607232)]} 0 1
[junit4:junit4]   2> 5908 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10 (1420626751346704384)]} 0 0
[junit4:junit4]   2> 5909 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10010 (1420626751347752960)]} 0 0
[junit4:junit4]   2> 5911 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10 (1420626751349850112)]} 0 0
[junit4:junit4]   2> 5912 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10010 (1420626751350898688)]} 0 0
[junit4:junit4]   2> 5914 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10 (-1420626751352995840)]} 0 0
[junit4:junit4]   2> 5915 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10010 (-1420626751354044416)]} 0 0
[junit4:junit4]   2> 5916 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[10 (-1420626751355092992)]} 0 0
[junit4:junit4]   2> 5917 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[10010 (-1420626751356141568)]} 0 0
[junit4:junit4]   2> 5919 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[11 (1420626751357190144)]} 0 1
[junit4:junit4]   2> 5920 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10011 (1420626751358238720)]} 0 1
[junit4:junit4]   2> 5921 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[11 (1420626751360335872)]} 0 0
[junit4:junit4]   2> 5923 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10011 (1420626751361384448)]} 0 1
[junit4:junit4]   2> 5924 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[12 (1420626751363481600)]} 0 0
[junit4:junit4]   2> 5925 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10012 (1420626751364530176)]} 0 0
[junit4:junit4]   2> 5928 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[12 (1420626751366627328)]} 0 1
[junit4:junit4]   2> 5929 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10012 (1420626751368724480)]} 0 0
[junit4:junit4]   2> 5930 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[11 (-1420626751369773056)]} 0 0
[junit4:junit4]   2> 5931 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10011 (-1420626751370821632)]} 0 0
[junit4:junit4]   2> 5932 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[11 (-1420626751371870208)]} 0 0
[junit4:junit4]   2> 5934 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10011 (-1420626751373967360)]} 0 0
[junit4:junit4]   2> 5964 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[13 (1420626751373967360)]} 0 30
[junit4:junit4]   2> 5966 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10013 (1420626751406473216)]} 0 1
[junit4:junit4]   2> 5967 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[13 (1420626751408570368)]} 0 0
[junit4:junit4]   2> 5968 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10013 (1420626751409618944)]} 0 0
[junit4:junit4]   2> 5971 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10014 (1420626751411716097)]} 0 1
[junit4:junit4]   2> 5971 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[14 (1420626751411716096)]} 0 1
[junit4:junit4]   2> 5974 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10014 (1420626751414861824)]} 0 0
[junit4:junit4]   2> 5975 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[14 (1420626751414861825)]} 0 2
[junit4:junit4]   2> 5976 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10012 (-1420626751418007552)]} 0 0
[junit4:junit4]   2> 5977 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[12 (-1420626751419056128)]} 0 0
[junit4:junit4]   2> 5978 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10012 (-1420626751420104704)]} 0 0
[junit4:junit4]   2> 5979 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[12 (-1420626751421153280)]} 0 0
[junit4:junit4]   2> 5981 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10015 (1420626751422201856)]} 0 1
[junit4:junit4]   2> 5982 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[15 (1420626751423250432)]} 0 1
[junit4:junit4]   2> 5983 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10015 (1420626751425347584)]} 0 0
[junit4:junit4]   2> 5984 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[15 (1420626751426396160)]} 0 1
[junit4:junit4]   2> 5986 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10016 (1420626751428493312)]} 0 0
[junit4:junit4]   2> 5987 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[16 (1420626751428493313)]} 0 1
[junit4:junit4]   2> 5990 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[16 (1420626751431639041)]} 0 1
[junit4:junit4]   2> 5991 T1283 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5991 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10016 (1420626751431639040)]} 0 2
[junit4:junit4]   2> 5993 T1283 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55429/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55429_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5995 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10017 (1420626751436881921)]} 0 1
[junit4:junit4]   2> 5995 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[17 (1420626751436881920)]} 0 2
[junit4:junit4]   2> 5998 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10017 (1420626751440027648)]} 0 1
[junit4:junit4]   2> 5998 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[17 (1420626751440027649)]} 0 1
[junit4:junit4]   2> 6001 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10018 (1420626751443173376)]} 0 1
[junit4:junit4]   2> 6001 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[18 (1420626751443173377)]} 0 1
[junit4:junit4]   2> 6004 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10018 (1420626751446319104)]} 0 1
[junit4:junit4]   2> 6004 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[18 (1420626751447367680)]} 0 0
[junit4:junit4]   2> 6007 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10019 (1420626751449464832)]} 0 1
[junit4:junit4]   2> 6007 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[19 (1420626751449464833)]} 0 1
[junit4:junit4]   2> 6009 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10019 (1420626751452610560)]} 0 0
[junit4:junit4]   2> 6010 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[19 (1420626751452610561)]} 0 1
[junit4:junit4]   2> 6012 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10020 (1420626751455756288)]} 0 0
[junit4:junit4]   2> 6013 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[20 (1420626751456804864)]} 0 1
[junit4:junit4]   2> 6015 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10020 (1420626751458902016)]} 0 0
[junit4:junit4]   2> 6016 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[20 (1420626751458902017)]} 0 1
[junit4:junit4]   2> 6019 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[21 (1420626751462047745)]} 0 1
[junit4:junit4]   2> 6019 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10021 (1420626751462047744)]} 0 1
[junit4:junit4]   2> 6022 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[21 (1420626751465193472)]} 0 1
[junit4:junit4]   2> 6022 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10021 (1420626751466242048)]} 0 1
[junit4:junit4]   2> 6025 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[22 (1420626751468339200)]} 0 1
[junit4:junit4]   2> 6025 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10022 (1420626751468339201)]} 0 1
[junit4:junit4]   2> 6028 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[22 (1420626751471484928)]} 0 1
[junit4:junit4]   2> 6028 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10022 (1420626751472533504)]} 0 0
[junit4:junit4]   2> 6030 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[18 (-1420626751474630656)]} 0 0
[junit4:junit4]   2> 6031 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10018 (-1420626751474630657)]} 0 1
[junit4:junit4]   2> 6032 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[18 (-1420626751476727808)]} 0 0
[junit4:junit4]   2> 6033 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10018 (-1420626751477776384)]} 0 0
[junit4:junit4]   2> 6035 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[23 (1420626751478824960)]} 0 1
[junit4:junit4]   2> 6035 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10023 (1420626751479873536)]} 0 0
[junit4:junit4]   2> 6038 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[23 (1420626751483019264)]} 0 1
[junit4:junit4]   2> 6039 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10023 (1420626751483019265)]} 0 1
[junit4:junit4]   2> 6041 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[20 (-1420626751486164992)]} 0 1
[junit4:junit4]   2> 6041 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10020 (-1420626751486164993)]} 0 0
[junit4:junit4]   2> 6043 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[20 (-1420626751488262144)]} 0 0
[junit4:junit4]   2> 6043 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10020 (-1420626751488262145)]} 0 0
[junit4:junit4]   2> 6046 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[24 (1420626751490359296)]} 0 1
[junit4:junit4]   2> 6046 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10024 (1420626751490359297)]} 0 1
[junit4:junit4]   2> 6049 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[24 (1420626751493505024)]} 0 1
[junit4:junit4]   2> 6050 T1288 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6050 T1299 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6050 T1282 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6050 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10024 (1420626751494553600)]} 0 1
[junit4:junit4]   2> 6051 T1312 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6054 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[25 (1420626751498747904)]} 0 1
[junit4:junit4]   2> 6054 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10025 (1420626751499796480)]} 0 0
[junit4:junit4]   2>  C113_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:55429/solr, state=recovering, node_name=127.0.0.1:55429_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 6062 T1308 C113 P55429 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1354815240055/jetty2
[junit4:junit4]   2> 6064 T1308 C113 P55429 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@42c042c0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@4bae4bae),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6064 T1308 C113 P55429 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6066 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 6066 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 6067 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[25 (1420626751501893632)]} 0 11
[junit4:junit4]   2> 6068 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10025 (1420626751503990784)]} 0 10
[junit4:junit4]   2> 6070 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[26 (1420626751515525120)]} 0 1
[junit4:junit4]   2> 6071 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10026 (1420626751516573696)]} 0 1
[junit4:junit4]   2> 6077 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6078 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10026 (1420626751520768000)]} 0 4
[junit4:junit4]   2> 6079 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6080 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[26 (1420626751520768001)]} 0 6
[junit4:junit4]   2> 6081 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10027 (1420626751528108032)]} 0 0
[junit4:junit4]   2> 6105 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[27 (1420626751530205184)]} 0 23
[junit4:junit4]   2> 6108 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6109 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10027 (1420626751530205184)]} 0 26
[junit4:junit4]   2> 6111 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6112 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[27 (1420626751556419584)]} 0 4
[junit4:junit4]   2> 6112 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10028 (1420626751559565312)]} 0 1
[junit4:junit4]   2> 6115 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[28 (1420626751562711040)]} 0 1
[junit4:junit4]   2> 6118 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6119 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10028 (1420626751563759616)]} 0 4
[junit4:junit4]   2> 6120 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6121 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[28 (1420626751565856768)]} 0 4
[junit4:junit4]   2> 6121 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10023 (-1420626751570051072)]} 0 0
[junit4:junit4]   2> 6123 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[23 (-1420626751572148224)]} 0 0
[junit4:junit4]   2> 6125 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6126 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10023 (-1420626751573196800)]} 0 2
[junit4:junit4]   2> 6126 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6128 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[23 (-1420626751574245376)]} 0 3
[junit4:junit4]   2> 6129 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10029 (1420626751577391104)]} 0 1
[junit4:junit4]   2> 6131 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[29 (1420626751579488256)]} 0 1
[junit4:junit4]   2> 6134 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6135 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10029 (1420626751580536832)]} 0 4
[junit4:junit4]   2> 6136 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6137 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[29 (1420626751582633984)]} 0 4
[junit4:junit4]   2> 6138 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10030 (1420626751586828288)]} 0 1
[junit4:junit4]   2> 6139 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[30 (1420626751588925440)]} 0 0
[junit4:junit4]   2> 6143 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6144 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10030 (1420626751589974016)]} 0 4
[junit4:junit4]   2> 6145 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6146 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[30 (1420626751592071168)]} 0 4
[junit4:junit4]   2> 6146 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10031 (1420626751596265472)]} 0 0
[junit4:junit4]   2> 6149 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[31 (1420626751599411200)]} 0 1
[junit4:junit4]   2> 6153 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6154 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6154 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10031 (1420626751599411200)]} 0 5
[junit4:junit4]   2> 6156 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[31 (1420626751602556928)]} 0 5
[junit4:junit4]   2> 6158 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10032 (1420626751607799808)]} 0 1
[junit4:junit4]   2> 6158 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[32 (1420626751608848384)]} 0 0
[junit4:junit4]   2> 6163 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6165 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10032 (1420626751610945536)]} 0 5
[junit4:junit4]   2> 6165 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6166 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[32 (1420626751611994112)]} 0 5
[junit4:junit4]   2> 6167 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10024 (-1420626751618285568)]} 0 0
[junit4:junit4]   2> 6169 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[24 (-1420626751620382720)]} 0 0
[junit4:junit4]   2> 6171 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6172 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10024 (-1420626751620382720)]} 0 3
[junit4:junit4]   2> 6173 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6174 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[24 (-1420626751622479872)]} 0 3
[junit4:junit4]   2> 6175 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10033 (1420626751625625600)]} 0 1
[junit4:junit4]   2> 6177 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[33 (1420626751627722752)]} 0 1
[junit4:junit4]   2> 6181 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6182 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10033 (1420626751628771328)]} 0 5
[junit4:junit4]   2> 6184 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6184 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10025 (-1420626751636111360)]} 0 0
[junit4:junit4]   2> 6185 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[33 (1420626751631917056)]} 0 5
[junit4:junit4]   2> 6187 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[25 (-1420626751639257088)]} 0 0
[junit4:junit4]   2> 6188 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6189 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[10025 (-1420626751639257088)]} 0 2
[junit4:junit4]   2> 6192 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10034 (1420626751643451392)]} 0 1
[junit4:junit4]   2> 6193 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6194 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[25 (-1420626751642402816)]} 0 4
[junit4:junit4]   2> 6198 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[34 (1420626751648694272)]} 0 2
[junit4:junit4]   2> 6198 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6199 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10034 (1420626751646597120)]} 0 5
[junit4:junit4]   2> 6201 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10029 (-1420626751653937152)]} 0 0
[junit4:junit4]   2> 6203 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6204 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[34 (1420626751652888576)]} 0 4
[junit4:junit4]   2> 6205 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6206 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[29 (-1420626751659180032)]} 0 0
[junit4:junit4]   2> 6206 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10029 (-1420626751657082880)]} 0 2
[junit4:junit4]   2> 6209 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10035 (1420626751662325760)]} 0 0
[junit4:junit4]   2> 6211 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6212 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[29 (-1420626751662325760)]} 0 3
[junit4:junit4]   2> 6214 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6215 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[35 (1420626751667568640)]} 0 1
[junit4:junit4]   2> 6216 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10035 (1420626751665471488)]} 0 4
[junit4:junit4]   2> 6218 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10030 (-1420626751671762944)]} 0 0
[junit4:junit4]   2> 6220 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6221 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[35 (1420626751670714368)]} 0 4
[junit4:junit4]   2> 6222 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6223 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10030 (-1420626751674908672)]} 0 3
[junit4:junit4]   2> 6223 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[30 (-1420626751677005824)]} 0 0
[junit4:junit4]   2> 6227 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10036 (1420626751679102976)]} 0 2
[junit4:junit4]   2> 6227 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6229 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[30 (-1420626751680151552)]} 0 3
[junit4:junit4]   2> 6231 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[36 (1420626751685394432)]} 0 0
[junit4:junit4]   2> 6232 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6233 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10036 (1420626751683297280)]} 0 4
[junit4:junit4]   2> 6235 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10031 (-1420626751689588736)]} 0 0
[junit4:junit4]   2> 6237 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6238 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[36 (1420626751688540160)]} 0 4
[junit4:junit4]   2> 6239 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6240 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[10031 (-1420626751692734464)]} 0 2
[junit4:junit4]   2> 6240 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[31 (-1420626751694831616)]} 0 0
[junit4:junit4]   2> 6243 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10037 (1420626751696928768)]} 0 1
[junit4:junit4]   2> 6244 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6245 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[31 (-1420626751697977344)]} 0 2
[junit4:junit4]   2> 6248 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[37 (1420626751703220224)]} 0 0
[junit4:junit4]   2> 6249 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6250 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10037 (1420626751700074496)]} 0 5
[junit4:junit4]   2> 6252 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10032 (-1420626751707414528)]} 0 0
[junit4:junit4]   2> 6253 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6254 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[37 (1420626751705317376)]} 0 4
[junit4:junit4]   2> 6256 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6256 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[32 (-1420626751711608832)]} 0 0
[junit4:junit4]   2> 6257 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10032 (-1420626751709511680)]} 0 3
[junit4:junit4]   2> 6283 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6284 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[32 (-1420626751714754560)]} 0 25
[junit4:junit4]   2> 6285 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10038 (1420626751715803136)]} 0 26
[junit4:junit4]   2> 6287 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[38 (1420626751744114688)]} 0 0
[junit4:junit4]   2> 6291 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6291 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10038 (1420626751745163264)]} 0 3
[junit4:junit4]   2> 6293 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6294 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10039 (1420626751751454720)]} 0 1
[junit4:junit4]   2> 6294 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[38 (1420626751746211840)]} 0 5
[junit4:junit4]   2> 6297 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[39 (1420626751754600448)]} 0 0
[junit4:junit4]   2> 6299 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6300 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10039 (1420626751753551872)]} 0 4
[junit4:junit4]   2> 6303 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10040 (1420626751759843328)]} 0 1
[junit4:junit4]   2> 6303 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6305 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[39 (1420626751757746176)]} 0 5
[junit4:junit4]   2> 6307 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[40 (1420626751765086208)]} 0 0
[junit4:junit4]   2> 6310 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6311 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10040 (1420626751764037632)]} 0 5
[junit4:junit4]   2> 6313 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6313 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10033 (-1420626751771377664)]} 0 0
[junit4:junit4]   2> 6315 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[40 (1420626751768231936)]} 0 5
[junit4:junit4]   2> 6317 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[33 (-1420626751775571968)]} 0 0
[junit4:junit4]   2> 6318 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6319 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[10033 (-1420626751774523392)]} 0 3
[junit4:junit4]   2> 6321 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6321 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[33 (-1420626751777669120)]} 0 2
[junit4:junit4]   2> 6323 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10041 (1420626751779766272)]} 0 2
[junit4:junit4]   2> 6325 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[41 (1420626751782912000)]} 0 1
[junit4:junit4]   2> 6330 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6331 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6331 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10041 (1420626751785009152)]} 0 5
[junit4:junit4]   2> 6332 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[41 (1420626751786057728)]} 0 5
[junit4:junit4]   2> 6335 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10042 (1420626751793397760)]} 0 1
[junit4:junit4]   2> 6336 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[42 (1420626751794446336)]} 0 1
[junit4:junit4]   2> 6343 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6344 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10042 (1420626751798640640)]} 0 5
[junit4:junit4]   2> 6345 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6346 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[42 (1420626751799689216)]} 0 6
[junit4:junit4]   2> 6347 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10034 (-1420626751807029248)]} 0 1
[junit4:junit4]   2> 6348 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[34 (-1420626751808077824)]} 0 0
[junit4:junit4]   2> 6351 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6352 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[10034 (-1420626751809126400)]} 0 3
[junit4:junit4]   2> 6354 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6355 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10043 (1420626751814369280)]} 0 1
[junit4:junit4]   2> 6355 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[34 (-1420626751811223552)]} 0 4
[junit4:junit4]   2> 6358 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[43 (1420626751818563584)]} 0 0
[junit4:junit4]   2> 6360 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6361 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10043 (1420626751817515008)]} 0 4
[junit4:junit4]   2> 6363 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10036 (-1420626751823806464)]} 0 0
[junit4:junit4]   2> 6364 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6365 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[43 (1420626751821709312)]} 0 4
[junit4:junit4]   2> 6367 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[36 (-1420626751828000768)]} 0 0
[junit4:junit4]   2> 6368 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6369 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10036 (-1420626751826952192)]} 0 3
[junit4:junit4]   2> 6371 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10044 (1420626751832195072)]} 0 0
[junit4:junit4]   2> 6373 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6373 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[36 (-1420626751831146496)]} 0 3
[junit4:junit4]   2> 6377 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6377 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[44 (1420626751837437952)]} 0 1
[junit4:junit4]   2> 6378 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10044 (1420626751835340800)]} 0 4
[junit4:junit4]   2> 6381 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10045 (1420626751841632256)]} 0 1
[junit4:junit4]   2> 6383 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6384 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[44 (1420626751841632256)]} 0 4
[junit4:junit4]   2> 6386 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6387 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[45 (1420626751847923712)]} 0 1
[junit4:junit4]   2> 6388 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10045 (1420626751845826560)]} 0 5
[junit4:junit4]   2> 6390 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10046 (1420626751852118016)]} 0 0
[junit4:junit4]   2> 6392 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6393 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[45 (1420626751851069440)]} 0 4
[junit4:junit4]   2> 6396 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[46 (1420626751857360896)]} 0 1
[junit4:junit4]   2> 6397 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 6397 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10046 (1420626751854215168)]} 0 5
[junit4:junit4]   2> 6400 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10047 (1420626751862603776)]} 0 0
[junit4:junit4]   2> 6402 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6403 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[46 (1420626751860506624)]} 0 5
[junit4:junit4]   2> 6428 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[47 (1420626751891963904)]} 0 1
[junit4:junit4]   2> 6429 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6430 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10047 (1420626751865749504)]} 0 27
[junit4:junit4]   2> 6434 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10048 (1420626751897206784)]} 0 1
[junit4:junit4]   2> 6435 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6436 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[47 (1420626751894061056)]} 0 6
[junit4:junit4]   2> 6440 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6440 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[48 (1420626751902449664)]} 0 2
[junit4:junit4]   2> 6441 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10048 (1420626751900352512)]} 0 5
[junit4:junit4]   2> 6444 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10041 (-1420626751908741120)]} 0 0
[junit4:junit4]   2> 6446 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6447 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[48 (1420626751907692544)]} 0 4
[junit4:junit4]   2> 6449 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[41 (-1420626751913984000)]} 0 0
[junit4:junit4]   2> 6450 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6451 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10041 (-1420626751910838272)]} 0 5
[junit4:junit4]   2> 6453 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6453 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10049 (1420626751918178304)]} 0 0
[junit4:junit4]   2> 6454 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[41 (-1420626751917129728)]} 0 2
[junit4:junit4]   2> 6458 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[49 (1420626751922372608)]} 0 1
[junit4:junit4]   2> 6459 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6460 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10049 (1420626751922372608)]} 0 4
[junit4:junit4]   2> 6463 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10043 (-1420626751927615488)]} 0 1
[junit4:junit4]   2> 6463 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6464 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[49 (1420626751925518336)]} 0 4
[junit4:junit4]   2> 6466 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[43 (-1420626751931809792)]} 0 0
[junit4:junit4]   2> 6467 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6468 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10043 (-1420626751930761216)]} 0 3
[junit4:junit4]   2> 6470 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6471 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[43 (-1420626751934955520)]} 0 2
[junit4:junit4]   2> 6472 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10050 (1420626751937052672)]} 0 1
[junit4:junit4]   2> 6474 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[50 (1420626751940198400)]} 0 0
[junit4:junit4]   2> 6478 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6479 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10050 (1420626751942295552)]} 0 3
[junit4:junit4]   2> 6481 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6481 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[50 (1420626751943344128)]} 0 4
[junit4:junit4]   2> 6482 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10044 (-1420626751948587008)]} 0 0
[junit4:junit4]   2> 6483 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[44 (-1420626751949635584)]} 0 0
[junit4:junit4]   2> 6486 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6487 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10044 (-1420626751950684160)]} 0 3
[junit4:junit4]   2> 6487 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6488 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[44 (-1420626751952781312)]} 0 3
[junit4:junit4]   2> 6490 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10051 (1420626751955927040)]} 0 1
[junit4:junit4]   2> 6491 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[51 (1420626751956975616)]} 0 1
[junit4:junit4]   2> 6495 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6496 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10051 (1420626751959072768)]} 0 4
[junit4:junit4]   2> 6497 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6498 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[51 (1420626751960121344)]} 0 5
[junit4:junit4]   2> 6498 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10046 (-1420626751965364224)]} 0 0
[junit4:junit4]   2> 6500 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[46 (-1420626751967461376)]} 0 0
[junit4:junit4]   2> 6503 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6503 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[10046 (-1420626751968509952)]} 0 2
[junit4:junit4]   2> 6504 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6505 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[46 (-1420626751969558528)]} 0 3
[junit4:junit4]   2> 6508 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10052 (1420626751973752832)]} 0 2
[junit4:junit4]   2> 6508 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[52 (1420626751975849984)]} 0 0
[junit4:junit4]   2> 6513 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6513 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6514 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10052 (1420626751977947136)]} 0 4
[junit4:junit4]   2> 6514 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[52 (1420626751978995712)]} 0 3
[junit4:junit4]   2> 6519 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10053 (1420626751987384321)]} 0 0
[junit4:junit4]   2> 6520 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[53 (1420626751987384320)]} 0 1
[junit4:junit4]   2> 6525 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6525 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6527 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10053 (1420626751990530048)]} 0 5
[junit4:junit4]   2> 6527 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[53 (1420626751990530049)]} 0 5
[junit4:junit4]   2> 6530 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10054 (1420626751997870080)]} 0 1
[junit4:junit4]   2> 6531 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[54 (1420626751998918656)]} 0 1
[junit4:junit4]   2> 6535 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6536 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10054 (1420626752001015808)]} 0 4
[junit4:junit4]   2> 6539 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6539 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10055 (1420626752007307264)]} 0 1
[junit4:junit4]   2> 6540 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[54 (1420626752002064384)]} 0 7
[junit4:junit4]   2> 6543 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[55 (1420626752012550144)]} 0 0
[junit4:junit4]   2> 6545 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6546 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10055 (1420626752011501568)]} 0 4
[junit4:junit4]   2> 6550 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10056 (1420626752018841600)]} 0 1
[junit4:junit4]   2> 6550 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6551 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[55 (1420626752015695872)]} 0 5
[junit4:junit4]   2> 6555 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6556 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[56 (1420626752024084480)]} 0 3
[junit4:junit4]   2> 6556 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10056 (1420626752021987328)]} 0 4
[junit4:junit4]   2> 6560 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10057 (1420626752029327360)]} 0 1
[junit4:junit4]   2> 6561 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6561 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[56 (1420626752028278784)]} 0 3
[junit4:junit4]   2> 6564 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[57 (1420626752034570240)]} 0 0
[junit4:junit4]   2> 6566 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6567 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10057 (1420626752032473088)]} 0 5
[junit4:junit4]   2> 6569 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10057 (-1420626752039813120)]} 0 0
[junit4:junit4]   2> 6570 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6571 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[57 (1420626752037715968)]} 0 4
[junit4:junit4]   2> 6574 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6574 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[57 (-1420626752045056000)]} 0 1
[junit4:junit4]   2> 6575 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10057 (-1420626752042958848)]} 0 3
[junit4:junit4]   2> 6578 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10058 (1420626752049250304)]} 0 1
[junit4:junit4]   2> 6579 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6580 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[57 (-1420626752048201728)]} 0 3
[junit4:junit4]   2> 6583 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[58 (1420626752053444608)]} 0 1
[junit4:junit4]   2> 6586 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6587 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10058 (1420626752052396032)]} 0 6
[junit4:junit4]   2> 6589 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6590 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[58 (1420626752057638912)]} 0 4
[junit4:junit4]   2> 6590 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10059 (1420626752060784640)]} 0 1
[junit4:junit4]   2> 6593 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[59 (1420626752063930368)]} 0 1
[junit4:junit4]   2> 6596 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6596 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10059 (1420626752064978944)]} 0 3
[junit4:junit4]   2> 6598 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6599 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10060 (1420626752071270400)]} 0 0
[junit4:junit4]   2> 6600 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[59 (1420626752067076096)]} 0 4
[junit4:junit4]   2> 6604 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[60 (1420626752075464704)]} 0 1
[junit4:junit4]   2> 6605 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6606 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10060 (1420626752074416128)]} 0 4
[junit4:junit4]   2> 6608 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10059 (-1420626752080707584)]} 0 0
[junit4:junit4]   2> 6609 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6610 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[60 (1420626752078610432)]} 0 4
[junit4:junit4]   2> 6612 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[59 (-1420626752084901888)]} 0 0
[junit4:junit4]   2> 6613 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6614 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10059 (-1420626752082804736)]} 0 4
[junit4:junit4]   2> 6617 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6617 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10061 (1420626752089096192)]} 0 1
[junit4:junit4]   2> 6618 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[59 (-1420626752088047616)]} 0 3
[junit4:junit4]   2> 6623 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[61 (1420626752095387648)]} 0 1
[junit4:junit4]   2> 6649 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6650 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10061 (1420626752095387648)]} 0 28
[junit4:junit4]   2> 6654 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10062 (1420626752126844928)]} 0 2
[junit4:junit4]   2> 6654 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6656 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[61 (1420626752124747776)]} 0 6
[junit4:junit4]   2> 6659 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[62 (1420626752133136384)]} 0 1
[junit4:junit4]   2> 6660 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6660 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10062 (1420626752132087808)]} 0 3
[junit4:junit4]   2> 6663 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10063 (1420626752137330688)]} 0 1
[junit4:junit4]   2> 6666 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6667 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[62 (1420626752136282112)]} 0 6
[junit4:junit4]   2> 6670 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[63 (1420626752144670720)]} 0 1
[junit4:junit4]   2> 6672 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6673 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10063 (1420626752143622144)]} 0 5
[junit4:junit4]   2> 6690 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10060 (-1420626752166690816)]} 0 0
[junit4:junit4]   2> 6691 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6692 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[63 (1420626752153059328)]} 0 15
[junit4:junit4]   2> 6694 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[60 (-1420626752170885120)]} 0 0
[junit4:junit4]   2> 6696 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6697 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10060 (-1420626752169836544)]} 0 4
[junit4:junit4]   2> 6699 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6700 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[60 (-1420626752174030848)]} 0 3
[junit4:junit4]   2> 6701 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10064 (1420626752177176576)]} 0 1
[junit4:junit4]   2> 6703 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[64 (1420626752179273728)]} 0 1
[junit4:junit4]   2> 6706 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6707 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10064 (1420626752180322304)]} 0 4
[junit4:junit4]   2> 6710 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10061 (-1420626752187662336)]} 0 0
[junit4:junit4]   2> 6712 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6713 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[64 (1420626752185565184)]} 0 5
[junit4:junit4]   2> 6715 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6715 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[61 (-1420626752192905216)]} 0 0
[junit4:junit4]   2> 6716 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[10061 (-1420626752190808064)]} 0 3
[junit4:junit4]   2> 6719 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10065 (1420626752196050944)]} 0 1
[junit4:junit4]   2> 6719 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6721 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[61 (-1420626752196050944)]} 0 3
[junit4:junit4]   2> 6724 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[65 (1420626752201293824)]} 0 1
[junit4:junit4]   2> 6724 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6725 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10065 (1420626752199196672)]} 0 4
[junit4:junit4]   2> 6728 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6728 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10066 (1420626752206536704)]} 0 1
[junit4:junit4]   2> 6729 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[65 (1420626752204439552)]} 0 3
[junit4:junit4]   2> 6732 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[66 (1420626752210731008)]} 0 0
[junit4:junit4]   2> 6736 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 6737 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10066 (1420626752209682432)]} 0 6
[junit4:junit4]   2> 6739 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6740 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[66 (1420626752213876736)]} 0 5
[junit4:junit4]   2> 6741 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10067 (1420626752219119616)]} 0 1
[junit4:junit4]   2> 6743 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[67 (1420626752221216768)]} 0 1
[junit4:junit4]   2> 6746 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6747 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10067 (1420626752222265344)]} 0 4
[junit4:junit4]   2> 6748 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6749 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[67 (1420626752224362496)]} 0 4
[junit4:junit4]   2> 6749 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10063 (-1420626752228556800)]} 0 0
[junit4:junit4]   2> 6752 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[63 (-1420626752231702528)]} 0 0
[junit4:junit4]   2> 6754 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6755 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10063 (-1420626752232751104)]} 0 2
[junit4:junit4]   2> 6756 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6757 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[63 (-1420626752234848256)]} 0 3
[junit4:junit4]   2> 6759 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10068 (1420626752237993984)]} 0 1
[junit4:junit4]   2> 6760 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[68 (1420626752240091136)]} 0 0
[junit4:junit4]   2> 6764 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6765 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10068 (1420626752241139712)]} 0 4
[junit4:junit4]   2> 6767 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6767 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10069 (1420626752247431168)]} 0 0
[junit4:junit4]   2> 6768 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[68 (1420626752243236864)]} 0 5
[junit4:junit4]   2> 6772 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[69 (1420626752251625472)]} 0 1
[junit4:junit4]   2> 6773 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6774 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10069 (1420626752250576896)]} 0 4
[junit4:junit4]   2> 6777 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10066 (-1420626752256868352)]} 0 1
[junit4:junit4]   2> 6778 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6778 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[69 (1420626752255819776)]} 0 3
[junit4:junit4]   2> 6780 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[66 (-1420626752261062656)]} 0 0
[junit4:junit4]   2> 6781 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6782 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10066 (-1420626752260014080)]} 0 3
[junit4:junit4]   2> 6785 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10070 (1420626752265256960)]} 0 1
[junit4:junit4]   2> 6788 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6789 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[66 (-1420626752266305536)]} 0 4
[junit4:junit4]   2> 6792 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[70 (1420626752273645568)]} 0 0
[junit4:junit4]   2> 6794 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6795 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10070 (1420626752269451264)]} 0 7
[junit4:junit4]   2> 6797 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6797 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10071 (1420626752278888448)]} 0 0
[junit4:junit4]   2> 6798 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[70 (1420626752276791296)]} 0 3
[junit4:junit4]   2> 6802 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[71 (1420626752283082752)]} 0 1
[junit4:junit4]   2> 6803 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6804 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10071 (1420626752283082752)]} 0 4
[junit4:junit4]   2> 6807 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6808 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[71 (1420626752286228480)]} 0 4
[junit4:junit4]   2> 6808 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10072 (1420626752290422784)]} 0 0
[junit4:junit4]   2> 6811 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[72 (1420626752292519936)]} 0 1
[junit4:junit4]   2> 6813 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6815 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[10072 (1420626752293568512)]} 0 4
[junit4:junit4]   2> 6818 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10073 (1420626752299859968)]} 0 1
[junit4:junit4]   2> 6819 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6820 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[72 (1420626752296714240)]} 0 6
[junit4:junit4]   2> 6824 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6825 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10073 (1420626752303005696)]} 0 5
[junit4:junit4]   2> 6827 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[73 (1420626752308248576)]} 0 2
[junit4:junit4]   2> 6828 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10074 (1420626752310345728)]} 0 1
[junit4:junit4]   2> 6834 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6834 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6835 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10074 (1420626752313491457)]} 0 5
[junit4:junit4]   2> 6836 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[73 (1420626752313491456)]} 0 6
[junit4:junit4]   2> 6838 T1277 C115 P37805 /update {version=2&wt=javabin} {delete=[10069 (-1420626752321880064)]} 0 0
[junit4:junit4]   2> 6839 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[74 (1420626752322928640)]} 0 0
[junit4:junit4]   2> 6843 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6843 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10069 (-1420626752325025792)]} 0 2
[junit4:junit4]   2> 6846 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6847 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10075 (1420626752330268672)]} 0 1
[junit4:junit4]   2> 6847 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[74 (1420626752326074368)]} 0 5
[junit4:junit4]   2> 6850 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[69 (-1420626752334462976)]} 0 0
[junit4:junit4]   2> 6853 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6854 T1296 C114 P44186 /update {version=2&wt=javabin} {add=[10075 (1420626752334462976)]} 0 5
[junit4:junit4]   2> 6856 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6857 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[69 (-1420626752338657280)]} 0 3
[junit4:junit4]   2> 6882 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10076 (1420626752340754432)]} 0 26
[junit4:junit4]   2> 6889 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6891 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10076 (1420626752371163136)]} 0 7
[junit4:junit4]   2> 6893 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[10074 (-1420626752379551744)]} 0 0
[junit4:junit4]   2> 6898 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6899 T1294 C114 P44186 /update {version=2&wt=javabin} {delete=[10074 (-1420626752382697472)]} 0 3
[junit4:junit4]   2> 6933 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10077 (1420626752387940352)]} 0 32
[junit4:junit4]   2> 6933 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[75 (1420626752370114560)]} 0 49
[junit4:junit4]   2> 6941 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6941 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6941 T1296 C114 P44186 /update {version=2&wt=javabin} {add=[10077 (1420626752423591936)]} 0 6
[junit4:junit4]   2> 6942 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[75 (1420626752424640512)]} 0 6
[junit4:junit4]   2> 6945 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10078 (1420626752433029120)]} 0 1
[junit4:junit4]   2> 6946 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[76 (1420626752434077696)]} 0 1
[junit4:junit4]   2> 6950 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6951 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6951 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10078 (1420626752437223424)]} 0 3
[junit4:junit4]   2> 6952 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[76 (1420626752437223425)]} 0 4
[junit4:junit4]   2> 6955 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10079 (1420626752443514880)]} 0 1
[junit4:junit4]   2> 6955 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[74 (-1420626752444563456)]} 0 0
[junit4:junit4]   2> 6960 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6960 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6961 T1297 C114 P44186 /update {version=2&wt=javabin} {delete=[74 (-1420626752447709184)]} 0 3
[junit4:junit4]   2> 6962 T1296 C114 P44186 /update {version=2&wt=javabin} {add=[10079 (1420626752446660608)]} 0 5
[junit4:junit4]   2> 6964 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[77 (1420626752454000640)]} 0 0
[junit4:junit4]   2> 6966 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[10080 (1420626752456097792)]} 0 0
[junit4:junit4]   2> 6970 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6971 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[77 (1420626752457146368)]} 0 4
[junit4:junit4]   2> 6973 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6974 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[78 (1420626752463437824)]} 0 1
[junit4:junit4]   2> 6975 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10080 (1420626752459243520)]} 0 6
[junit4:junit4]   2> 6977 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10075 (-1420626752467632128)]} 0 0
[junit4:junit4]   2> 6982 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 6982 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 6983 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[10075 (-1420626752470777856)]} 0 3
[junit4:junit4]   2> 6984 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[78 (1420626752467632128)]} 0 7
[junit4:junit4]   2> 6987 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10081 (1420626752477069312)]} 0 1
[junit4:junit4]   2> 6987 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[79 (1420626752478117888)]} 0 0
[junit4:junit4]   2> 6993 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6993 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 6994 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10081 (1420626752480215040)]} 0 5
[junit4:junit4]   2> 6995 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[79 (1420626752481263616)]} 0 5
[junit4:junit4]   2> 6998 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10082 (1420626752488603648)]} 0 1
[junit4:junit4]   2> 6998 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[80 (1420626752489652224)]} 0 0
[junit4:junit4]   2> 7004 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7005 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7005 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[80 (1420626752492797953)]} 0 4
[junit4:junit4]   2> 7006 T1296 C114 P44186 /update {version=2&wt=javabin} {add=[10082 (1420626752492797952)]} 0 5
[junit4:junit4]   2> 7010 T1280 C115 P37805 /update {version=2&wt=javabin} {delete=[75 (-1420626752502235137)]} 0 0
[junit4:junit4]   2> 7011 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10083 (1420626752502235136)]} 0 1
[junit4:junit4]   2> 7015 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7016 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[75 (-1420626752505380864)]} 0 3
[junit4:junit4]   2> 7019 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[81 (1420626752511672320)]} 0 1
[junit4:junit4]   2> 7025 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7026 T1296 C114 P44186 /update {version=2&wt=javabin} {add=[81 (1420626752514818048)]} 0 4
[junit4:junit4]   2> 7049 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[82 (1420626752522158080)]} 0 20
[junit4:junit4]   2> 7103 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7103 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7104 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[82 (1420626752546275328)]} 0 52
[junit4:junit4]   2> 7104 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10083 (1420626752506429440)]} 0 90
[junit4:junit4]   2> 7107 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10077 (-1420626752603947009)]} 0 0
[junit4:junit4]   2> 7107 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[83 (1420626752603947008)]} 0 1
[junit4:junit4]   2> 7111 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7112 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[10077 (-1420626752606044160)]} 0 3
[junit4:junit4]   2> 7115 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10084 (1420626752611287040)]} 0 1
[junit4:junit4]   2> 7115 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7116 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[83 (1420626752607092736)]} 0 6
[junit4:junit4]   2> 7119 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[77 (-1420626752615481344)]} 0 1
[junit4:junit4]   2> 7119 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7120 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10084 (1420626752614432768)]} 0 3
[junit4:junit4]   2> 7123 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10079 (-1420626752619675648)]} 0 1
[junit4:junit4]   2> 7123 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7124 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[77 (-1420626752618627072)]} 0 3
[junit4:junit4]   2> 7126 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7127 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[84 (1420626752623869952)]} 0 1
[junit4:junit4]   2> 7127 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[10079 (-1420626752622821376)]} 0 2
[junit4:junit4]   2> 7130 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10085 (1420626752628064256)]} 0 0
[junit4:junit4]   2> 7134 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7135 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[84 (1420626752627015680)]} 0 6
[junit4:junit4]   2> 7136 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7137 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10085 (1420626752631209984)]} 0 5
[junit4:junit4]   2> 7137 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[79 (-1420626752635404288)]} 0 0
[junit4:junit4]   2> 7140 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[10086 (1420626752637501440)]} 0 1
[junit4:junit4]   2> 7142 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7143 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[79 (-1420626752638550016)]} 0 3
[junit4:junit4]   2> 7146 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[85 (1420626752643792896)]} 0 1
[junit4:junit4]   2> 7146 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 7147 T1296 C114 P44186 /update {version=2&wt=javabin} {add=[10086 (1420626752640647168)]} 0 5
[junit4:junit4]   2> 7150 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10087 (1420626752647987200)]} 0 1
[junit4:junit4]   2> 7151 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7152 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[85 (1420626752646938624)]} 0 4
[junit4:junit4]   2> 7154 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[86 (1420626752653230080)]} 0 0
[junit4:junit4]   2> 7155 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7157 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10087 (1420626752651132928)]} 0 5
[junit4:junit4]   2> 7160 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7160 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[10085 (-1420626752659521536)]} 0 0
[junit4:junit4]   2> 7161 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[86 (1420626752656375808)]} 0 4
[junit4:junit4]   2> 7164 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[87 (1420626752662667264)]} 0 1
[junit4:junit4]   2> 7164 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7165 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[10085 (-1420626752662667264)]} 0 2
[junit4:junit4]   2> 7168 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10088 (1420626752667910144)]} 0 1
[junit4:junit4]   2> 7169 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7170 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[87 (1420626752665812992)]} 0 4
[junit4:junit4]   2> 7172 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7173 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[85 (-1420626752673153024)]} 0 0
[junit4:junit4]   2> 7173 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10088 (1420626752670007296)]} 0 3
[junit4:junit4]   2> 7177 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7178 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[85 (-1420626752675250176)]} 0 3
[junit4:junit4]   2> 7180 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10089 (1420626752677347328)]} 0 3
[junit4:junit4]   2> 7200 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[88 (1420626752681541632)]} 0 19
[junit4:junit4]   2> 7202 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7203 T1296 C114 P44186 /update {version=2&wt=javabin} {add=[10089 (1420626752701464576)]} 0 3
[junit4:junit4]   2> 7204 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7205 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[88 (1420626752703561728)]} 0 3
[junit4:junit4]   2> 7206 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10090 (1420626752706707456)]} 0 1
[junit4:junit4]   2> 7208 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[89 (1420626752708804608)]} 0 1
[junit4:junit4]   2> 7210 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7211 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10090 (1420626752709853184)]} 0 3
[junit4:junit4]   2> 7214 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10087 (-1420626752716144640)]} 0 0
[junit4:junit4]   2> 7214 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7215 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[89 (1420626752711950336)]} 0 5
[junit4:junit4]   2> 7217 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7218 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[90 (1420626752719290368)]} 0 1
[junit4:junit4]   2> 7218 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[10087 (-1420626752718241792)]} 0 2
[junit4:junit4]   2> 7221 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10091 (1420626752723484672)]} 0 0
[junit4:junit4]   2> 7224 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7225 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[90 (1420626752722436096)]} 0 5
[junit4:junit4]   2> 7226 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7226 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10091 (1420626752725581824)]} 0 3
[junit4:junit4]   2> 7227 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[87 (-1420626752729776128)]} 0 0
[junit4:junit4]   2> 7229 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10088 (-1420626752730824704)]} 0 1
[junit4:junit4]   2> 7231 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7232 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[87 (-1420626752731873280)]} 0 3
[junit4:junit4]   2> 7232 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7233 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[10088 (-1420626752733970432)]} 0 3
[junit4:junit4]   2> 7234 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[91 (1420626752737116160)]} 0 0
[junit4:junit4]   2> 7236 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10092 (1420626752738164736)]} 0 1
[junit4:junit4]   2> 7241 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7242 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 7242 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[91 (1420626752740261888)]} 0 5
[junit4:junit4]   2> 7243 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10092 (1420626752741310464)]} 0 5
[junit4:junit4]   2> 7245 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[88 (-1420626752748650496)]} 0 0
[junit4:junit4]   2> 7246 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10090 (-1420626752749699072)]} 0 0
[junit4:junit4]   2> 7249 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7249 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7250 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[88 (-1420626752751796224)]} 0 2
[junit4:junit4]   2> 7251 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10090 (-1420626752751796225)]} 0 3
[junit4:junit4]   2> 7254 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[92 (1420626752757039104)]} 0 1
[junit4:junit4]   2> 7254 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10093 (1420626752757039105)]} 0 1
[junit4:junit4]   2> 7258 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7258 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7259 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[92 (1420626752760184832)]} 0 3
[junit4:junit4]   2> 7260 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10093 (1420626752760184833)]} 0 4
[junit4:junit4]   2> 7261 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[90 (-1420626752765427712)]} 0 0
[junit4:junit4]   2> 7262 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10091 (-1420626752766476288)]} 0 0
[junit4:junit4]   2> 7265 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7265 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7266 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[90 (-1420626752767524864)]} 0 3
[junit4:junit4]   2> 7266 T1295 C114 P44186 /update {version=2&wt=javabin} {delete=[10091 (-1420626752768573440)]} 0 2
[junit4:junit4]   2> 7269 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[93 (1420626752772767744)]} 0 1
[junit4:junit4]   2> 7270 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10094 (1420626752773816320)]} 0 1
[junit4:junit4]   2> 7273 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7274 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7274 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[93 (1420626752775913472)]} 0 3
[junit4:junit4]   2> 7275 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10094 (1420626752776962048)]} 0 3
[junit4:junit4]   2> 7277 T1278 C115 P37805 /update {version=2&wt=javabin} {delete=[91 (-1420626752782204928)]} 0 0
[junit4:junit4]   2> 7279 T1279 C115 P37805 /update {version=2&wt=javabin} {add=[10095 (1420626752783253504)]} 0 1
[junit4:junit4]   2> 7281 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7282 T1296 C114 P44186 /update {version=2&wt=javabin} {delete=[91 (-1420626752784302080)]} 0 3
[junit4:junit4]   2> 7284 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7284 T1280 C115 P37805 /update {version=2&wt=javabin} {add=[94 (1420626752789544960)]} 0 0
[junit4:junit4]   2> 7285 T1295 C114 P44186 /update {version=2&wt=javabin} {add=[10095 (1420626752786399232)]} 0 4
[junit4:junit4]   2> 7288 T1277 C115 P37805 /update {version=2&wt=javabin} {add=[10096 (1420626752792690688)]} 0 1
[junit4:junit4]   2> 7289 T1308 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7289 T1297 C114 P44186 /update {version=2&wt=javabin} {add=[94 (1420626752791642112)]} 0 3
[junit4:junit4]   2> 7292 T1278 C115 P37805 /update {version=2&wt=javabin} {add=[95 (1420626752796884992)]} 0 1
[junit4:junit4]   2> 7292 T1310 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7293 T1294 C114 P44186 /update {version=2&wt=javabin} {add=[10096 (1420626752795836416)]} 0 3
[junit4:junit4]   2> 7296 T1279 C115 P37805 /update {version=2&wt=javabin} {delete=[10093 (-1420626752802127872)]} 0 0
[junit4:junit4]   2> 7297 T1309 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 7298 T1296 C114 P44186 /update {version=2&wt=javabin} {add=[95 (1420626752800030720)]} 0 4
[junit4:junit4]   2> 7300 T1307 C113 P55429 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44186/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 7301 T1280 C115 P37805 /update {version=2&w

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

it4]    > 	at java.io.FileInputStream.<init>(FileInputStream.java:137)
[junit4:junit4]    > 	at org.apache.commons.io.FileUtils.doCopyFile(FileUtils.java:935)
[junit4:junit4]    > 	at org.apache.commons.io.FileUtils.doCopyDirectory(FileUtils.java:1225)
[junit4:junit4]    > 	at org.apache.commons.io.FileUtils.doCopyDirectory(FileUtils.java:1223)
[junit4:junit4]    > 	at org.apache.commons.io.FileUtils.doCopyDirectory(FileUtils.java:1223)
[junit4:junit4]    > 	at org.apache.commons.io.FileUtils.copyDirectory(FileUtils.java:1186)
[junit4:junit4]    > 	at org.apache.commons.io.FileUtils.copyDirectory(FileUtils.java:1058)
[junit4:junit4]    > 	at org.apache.commons.io.FileUtils.copyDirectory(FileUtils.java:1027)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:236)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:693)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:611)
[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:738)
[junit4:junit4]   2> 428 T3762 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 441 T3761 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4]   2> 1205 T3765 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=uk_UA, timezone=America/Monterrey
[junit4:junit4]   2> NOTE: Linux 3.2.0-34-generic amd64/IBM Corporation 1.6.0 (64-bit)/cpus=8,threads=1,free=87581072,total=307329024
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestStressLucene, TestReplicationHandler, XmlUpdateRequestHandlerTest, TestReload, ShowFileRequestHandlerTest, DirectUpdateHandlerTest, RAMDirectoryFactoryTest, DisMaxRequestHandlerTest, DebugComponentTest, TestSolrDeletionPolicy2, DistributedQueryElevationComponentTest, TestDocumentBuilder, SolrCoreTest, TestSolrXMLSerializer, TestQuerySenderListener, FieldMutatingUpdateProcessorTest, ZkNodePropsTest, PolyFieldTest, TestFastWriter, DirectSolrSpellCheckerTest, OverseerTest, TestPluginEnable, LukeRequestHandlerTest, ScriptEngineTest, ZkSolrClientTest, TestSort, TestMultiCoreConfBootstrap, UUIDFieldTest, OpenExchangeRatesOrgProviderTest, BadIndexSchemaTest, SignatureUpdateProcessorFactoryTest, FullSolrCloudDistribCmdsTest, MultiTermTest, TestSurroundQueryParser, TestLRUCache, QueryEqualityTest, UpdateParamsTest, TestValueSourceCache, TermVectorComponentTest, ReturnFieldsTest, TestRecovery, TestMergePolicyConfig, BasicFunctionalityTest, TestBinaryField, SuggesterTest, TestRTGBase, FileUtilsTest, DOMUtilTest, RecoveryZkTest, SolrIndexConfigTest, TestCSVResponseWriter, BasicDistributedZk2Test, IndexSchemaTest, EchoParamsTest, ZkCLITest, SolrInfoMBeanTest, TestTrie, LeaderElectionTest, BasicDistributedZkTest, DistributedTermsComponentTest, StatsComponentTest, FileBasedSpellCheckerTest, ConvertedLegacyTest, PeerSyncTest, QueryElevationComponentTest, SortByFunctionTest, TestJmxIntegration, HighlighterTest, TestIndexSearcher, TestFunctionQuery, TestRemoteStreaming, TestCSVLoader, DocumentBuilderTest, CurrencyFieldTest, BinaryUpdateRequestHandlerTest, QueryParsingTest, StandardRequestHandlerTest, TestPHPSerializedResponseWriter, PingRequestHandlerTest, DocumentAnalysisRequestHandlerTest, SolrPluginUtilsTest, JSONWriterTest, RequiredFieldsTest, SuggesterFSTTest, TestQueryUtils, SolrCmdDistributorTest, SolrCoreCheckLockOnStartupTest, TestLuceneMatchVersion, TestPropInject, NoCacheHeaderTest, AlternateDirectoryTest, TestUpdate, SOLR749Test, DefaultValueUpdateProcessorTest, TestCollationField, NumericFieldsTest, OutputWriterTest, TestBM25SimilarityFactory, TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TimeZoneUtilsTest, URLClassifyProcessorTest, DateFieldTest, TestSuggestSpellingConverter, SystemInfoHandlerTest, CircularListTest, PathHierarchyTokenizerFactoryTest, SyncSliceTest]
[junit4:junit4] Completed on J1 in 1.53s, 1 test, 1 error <<< FAILURES!

[...truncated 51 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:348: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:438: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1167: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:831: There were test failures: 236 suites, 980 tests, 1 suite-level error, 1 error, 7 ignored (2 assumptions)

Total time: 35 minutes 16 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message