lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b79) - Build # 4675 - Failure!
Date Thu, 14 Mar 2013 13:35:07 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4675/
Java: 32bit/jdk1.8.0-ea-b79 -server -XX:+UseConcMarkSweepGC

2 tests failed.
REGRESSION:  org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch

Error Message:
Server at http://127.0.0.1:41498/odde/onenodecollectioncore returned non ok status:404, message:Can not find: /odde/onenodecollectioncore/update

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:41498/odde/onenodecollectioncore returned non ok status:404, message:Can not find: /odde/onenodecollectioncore/update
	at __randomizedtesting.SeedInfo.seed([3DDFDC9DF034DEE9:BC395285876BBED5]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	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:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	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:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


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

Error Message:
Still found shard

Stack Trace:
java.lang.AssertionError: Still found shard
	at __randomizedtesting.SeedInfo.seed([3DDFDC9DF034DEE9:BC395285876BBED5]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.UnloadDistributedZkTest.testUnloadShardAndCollection(UnloadDistributedZkTest.java:124)
	at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistributedZkTest.java:77)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	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:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	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:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9183 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest
[junit4:junit4]   2> 0 T710 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /ayf/d
[junit4:junit4]   2> 3 T710 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468
[junit4:junit4]   2> 4 T710 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T711 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 5 T711 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 5 T711 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 5 T711 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 6 T711 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 6 T711 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1363267029468/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 105 T710 oasc.ZkTestServer.run start zk server on port:41539
[junit4:junit4]   2> 105 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c5d93c
[junit4:junit4]   2> 106 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T716 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 107 T716 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 107 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53786
[junit4:junit4]   2> 107 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53786
[junit4:junit4]   2> 108 T714 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 242 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0000 with negotiated timeout 10000 for client /127.0.0.1:53786
[junit4:junit4]   2> 242 T716 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0000, negotiated timeout = 10000
[junit4:junit4]   2> 248 T717 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c5d93c name:ZooKeeperConnection Watcher:127.0.0.1:41539 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 248 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 249 T710 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 252 T715 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d690c09df0000
[junit4:junit4]   2> 253 T717 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 253 T712 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:53786 which had sessionid 0x13d690c09df0000
[junit4:junit4]   2> 253 T710 oaz.ZooKeeper.close Session: 0x13d690c09df0000 closed
[junit4:junit4]   2> 254 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1023f74
[junit4:junit4]   2> 254 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 255 T718 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 255 T718 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 255 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53787
[junit4:junit4]   2> 256 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53787
[junit4:junit4]   2> 257 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0001 with negotiated timeout 10000 for client /127.0.0.1:53787
[junit4:junit4]   2> 257 T718 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0001, negotiated timeout = 10000
[junit4:junit4]   2> 257 T719 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1023f74 name:ZooKeeperConnection Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 257 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 258 T710 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 261 T710 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 264 T710 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 266 T710 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 269 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 269 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 274 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 274 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 278 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 278 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 280 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 281 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 283 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 284 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 289 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 289 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 293 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 293 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 296 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 297 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 300 T710 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 301 T710 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 304 T715 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d690c09df0001
[junit4:junit4]   2> 304 T719 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 304 T712 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:53787 which had sessionid 0x13d690c09df0001
[junit4:junit4]   2> 304 T710 oaz.ZooKeeper.close Session: 0x13d690c09df0001 closed
[junit4:junit4]   2> 398 T710 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 404 T710 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:41302
[junit4:junit4]   2> 405 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 405 T710 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 406 T710 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1363267029769
[junit4:junit4]   2> 406 T710 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1363267029769/solr.xml
[junit4:junit4]   2> 406 T710 oasc.CoreContainer.<init> New CoreContainer 29453862
[junit4:junit4]   2> 407 T710 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1363267029769/'
[junit4:junit4]   2> 407 T710 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1363267029769/'
[junit4:junit4]   2> 425 T710 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 425 T710 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 425 T710 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 426 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 426 T710 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 426 T710 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 427 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 427 T710 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 427 T710 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 428 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 434 T710 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 444 T710 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41539/solr
[junit4:junit4]   2> 444 T710 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 445 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@17676
[junit4:junit4]   2> 446 T729 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 447 T729 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 447 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53789
[junit4:junit4]   2> 447 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53789
[junit4:junit4]   2> 449 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0002 with negotiated timeout 20000 for client /127.0.0.1:53789
[junit4:junit4]   2> 449 T729 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0002, negotiated timeout = 20000
[junit4:junit4]   2> 450 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 450 T730 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17676 name:ZooKeeperConnection Watcher:127.0.0.1:41539 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 450 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 452 T715 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d690c09df0002
[junit4:junit4]   2> 453 T712 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:53789 which had sessionid 0x13d690c09df0002
[junit4:junit4]   2> 453 T710 oaz.ZooKeeper.close Session: 0x13d690c09df0002 closed
[junit4:junit4]   2> 454 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 455 T730 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 458 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1155c48
[junit4:junit4]   2> 467 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 481 T731 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 482 T731 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 482 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53790
[junit4:junit4]   2> 482 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53790
[junit4:junit4]   2> 484 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0003 with negotiated timeout 20000 for client /127.0.0.1:53790
[junit4:junit4]   2> 484 T731 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0003, negotiated timeout = 20000
[junit4:junit4]   2> 484 T732 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1155c48 name:ZooKeeperConnection Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 484 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 486 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 488 T710 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 490 T710 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41302_ayf%2Fd
[junit4:junit4]   2> 492 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:41302_ayf%2Fd Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:41302_ayf%2Fd
[junit4:junit4]   2> 493 T710 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41302_ayf%2Fd
[junit4:junit4]   2> 497 T710 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 503 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0003 type:delete cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 504 T710 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 508 T710 oasc.Overseer.start Overseer (id=89343068043411459-127.0.0.1:41302_ayf%2Fd-n_0000000000) starting
[junit4:junit4]   2> 509 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 510 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 510 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 511 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 513 T734 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 513 T710 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 516 T710 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4:junit4]   2> 519 T710 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 522 T733 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 524 T710 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 524 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 525 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 531 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 533 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@8c47e4
[junit4:junit4]   2> 534 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 535 T736 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 535 T736 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 535 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53791
[junit4:junit4]   2> 536 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53791
[junit4:junit4]   2> 536 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0004 with negotiated timeout 10000 for client /127.0.0.1:53791
[junit4:junit4]   2> 536 T736 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0004, negotiated timeout = 10000
[junit4:junit4]   2> 537 T737 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8c47e4 name:ZooKeeperConnection Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 537 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 538 T710 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 546 T710 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 613 T710 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 615 T710 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50519
[junit4:junit4]   2> 616 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 616 T710 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 617 T710 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011
[junit4:junit4]   2> 617 T710 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011/solr.xml
[junit4:junit4]   2> 617 T710 oasc.CoreContainer.<init> New CoreContainer 724685
[junit4:junit4]   2> 618 T710 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011/'
[junit4:junit4]   2> 618 T710 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011/'
[junit4:junit4]   2> 638 T710 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 639 T710 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 639 T710 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 640 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 640 T710 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 640 T710 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 641 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 641 T710 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 642 T710 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 642 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 650 T710 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 665 T710 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41539/solr
[junit4:junit4]   2> 666 T710 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 666 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1e23d1c
[junit4:junit4]   2> 667 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 667 T747 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 669 T747 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 669 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53792
[junit4:junit4]   2> 670 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53792
[junit4:junit4]   2> 670 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0005 with negotiated timeout 20000 for client /127.0.0.1:53792
[junit4:junit4]   2> 670 T747 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0005, negotiated timeout = 20000
[junit4:junit4]   2> 671 T748 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e23d1c name:ZooKeeperConnection Watcher:127.0.0.1:41539 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 671 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 672 T715 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d690c09df0005
[junit4:junit4]   2> 673 T712 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:53792 which had sessionid 0x13d690c09df0005
[junit4:junit4]   2> 673 T710 oaz.ZooKeeper.close Session: 0x13d690c09df0005 closed
[junit4:junit4]   2> 673 T748 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 673 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 677 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c7a670
[junit4:junit4]   2> 678 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 678 T749 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 678 T749 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 678 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53793
[junit4:junit4]   2> 679 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53793
[junit4:junit4]   2> 680 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0006 with negotiated timeout 20000 for client /127.0.0.1:53793
[junit4:junit4]   2> 680 T749 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0006, negotiated timeout = 20000
[junit4:junit4]   2> 680 T750 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c7a670 name:ZooKeeperConnection Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 680 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 681 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0006 type:create cxid:0x1 zxid:0x2e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 683 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0006 type:create cxid:0x2 zxid:0x2f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 684 T710 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1687 T710 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50519_ayf%2Fd
[junit4:junit4]   2> 1688 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0006 type:delete cxid:0xb zxid:0x30 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50519_ayf%2Fd Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50519_ayf%2Fd
[junit4:junit4]   2> 1689 T710 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50519_ayf%2Fd
[junit4:junit4]   2> 1693 T732 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 1693 T750 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 1693 T737 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 1698 T710 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 1698 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1699 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1773 T710 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 1778 T710 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34239
[junit4:junit4]   2> 1782 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1783 T710 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1783 T710 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166
[junit4:junit4]   2> 1783 T710 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166/solr.xml
[junit4:junit4]   2> 1784 T710 oasc.CoreContainer.<init> New CoreContainer 31245243
[junit4:junit4]   2> 1784 T710 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166/'
[junit4:junit4]   2> 1784 T710 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166/'
[junit4:junit4]   2> 1802 T710 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 1802 T710 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1803 T710 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 1803 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1803 T710 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1804 T710 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1804 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1804 T710 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1805 T710 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1805 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 1811 T710 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1830 T710 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41539/solr
[junit4:junit4]   2> 1831 T710 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 1832 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@a96724
[junit4:junit4]   2> 1833 T761 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1833 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1833 T761 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 1833 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53794
[junit4:junit4]   2> 1834 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53794
[junit4:junit4]   2> 1835 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0007 with negotiated timeout 20000 for client /127.0.0.1:53794
[junit4:junit4]   2> 1835 T761 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0007, negotiated timeout = 20000
[junit4:junit4]   2> 1835 T762 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a96724 name:ZooKeeperConnection Watcher:127.0.0.1:41539 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1835 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1837 T715 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d690c09df0007
[junit4:junit4]   2> 1837 T712 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:53794 which had sessionid 0x13d690c09df0007
[junit4:junit4]   2> 1838 T762 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1838 T710 oaz.ZooKeeper.close Session: 0x13d690c09df0007 closed
[junit4:junit4]   2> 1838 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 1841 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@72c4c9
[junit4:junit4]   2> 1842 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1842 T763 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1843 T763 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 1843 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53795
[junit4:junit4]   2> 1844 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53795
[junit4:junit4]   2> 1844 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0008 with negotiated timeout 20000 for client /127.0.0.1:53795
[junit4:junit4]   2> 1845 T763 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0008, negotiated timeout = 20000
[junit4:junit4]   2> 1845 T764 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72c4c9 name:ZooKeeperConnection Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1845 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1846 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0008 type:create cxid:0x1 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1847 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0008 type:create cxid:0x2 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1849 T710 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2852 T710 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34239_ayf%2Fd
[junit4:junit4]   2> 2853 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0008 type:delete cxid:0xb zxid:0x38 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34239_ayf%2Fd Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34239_ayf%2Fd
[junit4:junit4]   2> 2854 T710 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34239_ayf%2Fd
[junit4:junit4]   2> 2857 T732 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 2857 T737 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 2857 T750 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 2857 T764 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 2862 T710 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 2863 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2863 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2950 T710 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 2955 T710 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:45146
[junit4:junit4]   2> 2956 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2956 T710 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2957 T710 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330
[junit4:junit4]   2> 2957 T710 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330/solr.xml
[junit4:junit4]   2> 2957 T710 oasc.CoreContainer.<init> New CoreContainer 18646001
[junit4:junit4]   2> 2958 T710 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330/'
[junit4:junit4]   2> 2958 T710 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330/'
[junit4:junit4]   2> 2985 T710 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 2986 T710 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2986 T710 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 2987 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2988 T710 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2988 T710 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2988 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2989 T710 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2989 T710 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2990 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3005 T710 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3020 T710 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41539/solr
[junit4:junit4]   2> 3020 T710 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3021 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f030cd
[junit4:junit4]   2> 3022 T775 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3022 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3022 T775 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 3023 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53796
[junit4:junit4]   2> 3023 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53796
[junit4:junit4]   2> 3024 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df0009 with negotiated timeout 20000 for client /127.0.0.1:53796
[junit4:junit4]   2> 3024 T775 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df0009, negotiated timeout = 20000
[junit4:junit4]   2> 3024 T776 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f030cd name:ZooKeeperConnection Watcher:127.0.0.1:41539 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3025 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3026 T715 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d690c09df0009
[junit4:junit4]   2> 3026 T776 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3027 T712 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:53796 which had sessionid 0x13d690c09df0009
[junit4:junit4]   2> 3026 T710 oaz.ZooKeeper.close Session: 0x13d690c09df0009 closed
[junit4:junit4]   2> 3027 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3032 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@df16c
[junit4:junit4]   2> 3033 T777 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3033 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3034 T777 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 3034 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53797
[junit4:junit4]   2> 3034 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53797
[junit4:junit4]   2> 3035 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df000a with negotiated timeout 20000 for client /127.0.0.1:53797
[junit4:junit4]   2> 3035 T777 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df000a, negotiated timeout = 20000
[junit4:junit4]   2> 3035 T778 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@df16c name:ZooKeeperConnection Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3035 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3036 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df000a type:create cxid:0x1 zxid:0x3e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3037 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df000a type:create cxid:0x2 zxid:0x3f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3039 T710 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4042 T710 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45146_ayf%2Fd
[junit4:junit4]   2> 4043 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df000a type:delete cxid:0xb zxid:0x40 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:45146_ayf%2Fd Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:45146_ayf%2Fd
[junit4:junit4]   2> 4044 T710 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:45146_ayf%2Fd
[junit4:junit4]   2> 4048 T764 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4048 T737 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4048 T750 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4049 T732 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4049 T778 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4054 T710 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 4055 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4055 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4122 T710 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 4125 T710 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:45936
[junit4:junit4]   2> 4126 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4126 T710 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4127 T710 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1363267033522
[junit4:junit4]   2> 4127 T710 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1363267033522/solr.xml
[junit4:junit4]   2> 4127 T710 oasc.CoreContainer.<init> New CoreContainer 13402862
[junit4:junit4]   2> 4128 T710 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1363267033522/'
[junit4:junit4]   2> 4128 T710 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1363267033522/'
[junit4:junit4]   2> 4145 T710 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 4145 T710 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4146 T710 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 4146 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4146 T710 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4147 T710 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4147 T710 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4148 T710 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4152 T710 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4153 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 4162 T710 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4176 T710 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:41539/solr
[junit4:junit4]   2> 4176 T710 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 4177 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@182ebcb
[junit4:junit4]   2> 4178 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4178 T789 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4179 T789 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 4179 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53798
[junit4:junit4]   2> 4180 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53798
[junit4:junit4]   2> 4180 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df000b with negotiated timeout 20000 for client /127.0.0.1:53798
[junit4:junit4]   2> 4181 T789 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df000b, negotiated timeout = 20000
[junit4:junit4]   2> 4181 T790 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@182ebcb name:ZooKeeperConnection Watcher:127.0.0.1:41539 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4181 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4182 T715 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d690c09df000b
[junit4:junit4]   2> 4183 T790 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 4183 T712 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:53798 which had sessionid 0x13d690c09df000b
[junit4:junit4]   2> 4183 T710 oaz.ZooKeeper.close Session: 0x13d690c09df000b closed
[junit4:junit4]   2> 4184 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 4186 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c9ade3
[junit4:junit4]   2> 4187 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4188 T791 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4188 T791 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 4188 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53799
[junit4:junit4]   2> 4189 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53799
[junit4:junit4]   2> 4189 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df000c with negotiated timeout 20000 for client /127.0.0.1:53799
[junit4:junit4]   2> 4189 T791 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df000c, negotiated timeout = 20000
[junit4:junit4]   2> 4190 T792 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c9ade3 name:ZooKeeperConnection Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4190 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4191 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df000c type:create cxid:0x1 zxid:0x46 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4192 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df000c type:create cxid:0x2 zxid:0x47 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4194 T710 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5196 T710 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45936_ayf%2Fd
[junit4:junit4]   2> 5197 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df000c type:delete cxid:0xb zxid:0x48 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:45936_ayf%2Fd Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:45936_ayf%2Fd
[junit4:junit4]   2> 5198 T710 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:45936_ayf%2Fd
[junit4:junit4]   2> 5202 T737 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5202 T732 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5202 T778 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5202 T750 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5203 T764 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5203 T792 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5211 T710 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5211 T710 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5212 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5217 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5229 T746 oasc.CoreContainer.create Creating SolrCore 'unloadcollection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011/unloadcollection1
[junit4:junit4]   2> 5229 T746 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 5230 T746 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:unloadcollection
[junit4:junit4]   2> 5230 T746 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 5231 T746 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 5232 T746 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection
[junit4:junit4]   2> 5234 T746 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 5235 T746 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011/unloadcollection1/'
[junit4:junit4]   2> 5264 T746 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 5297 T746 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5299 T746 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5303 T746 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5736 T746 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5746 T746 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5749 T746 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5763 T746 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5767 T746 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5772 T746 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5773 T746 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 5774 T746 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 5774 T746 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5776 T746 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 5776 T746 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 5776 T746 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5777 T746 oasc.SolrCore.<init> [unloadcollection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011/unloadcollection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267034690unloadcollection1_1n/
[junit4:junit4]   2> 5777 T746 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e3ada9
[junit4:junit4]   2> 5778 T746 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5779 T746 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267034690unloadcollection1_1n forceNew: false
[junit4:junit4]   2> 5779 T746 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267034690unloadcollection1_1n/index/
[junit4:junit4]   2> 5779 T746 oasc.SolrCore.initIndex WARNING [unloadcollection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267034690unloadcollection1_1n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5780 T746 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267034690unloadcollection1_1n/index forceNew: false
[junit4:junit4]   2> 5781 T746 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e50243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@18372e2),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5781 T746 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5783 T746 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5783 T746 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5784 T746 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5784 T746 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5785 T746 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5785 T746 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5785 T746 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5786 T746 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5786 T746 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5788 T746 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5790 T746 oass.SolrIndexSearcher.<init> Opening Searcher@174032c main
[junit4:junit4]   2> 5791 T746 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267034690unloadcollection1_1n/tlog
[junit4:junit4]   2> 5792 T746 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5792 T746 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5796 T794 oasc.SolrCore.registerSearcher [unloadcollection1] Registered new searcher Searcher@174032c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5797 T746 oasc.ZkController.publish publishing core=unloadcollection1 state=down
[junit4:junit4]   2> 5798 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0006 type:create cxid:0x40 zxid:0x4c txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 6541 T733 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6542 T733 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50519/ayf/d",
[junit4:junit4]   2> 	  "core":"unloadcollection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50519_ayf%2Fd"}
[junit4:junit4]   2> 6542 T733 oasc.Overseer$ClusterStateUpdater.createCollection Create collection unloadcollection with numShards 1
[junit4:junit4]   2> 6544 T733 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 6544 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0003 type:create cxid:0x46 zxid:0x4f txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 6550 T737 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 6550 T778 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 6551 T732 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 6551 T792 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 6551 T750 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 6551 T764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 6800 T746 oasc.CoreContainer.registerCore registering core: unloadcollection1
[junit4:junit4]   2> 6801 T746 oasc.ZkController.register Register replica - core:unloadcollection1 address:http://127.0.0.1:50519/ayf/d collection:unloadcollection shard:shard1
[junit4:junit4]   2> 6802 T746 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leader_elect/shard1/election
[junit4:junit4]   2> 6809 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0006 type:delete cxid:0x51 zxid:0x59 txntype:-1 reqpath:n/a Error Path:/solr/collections/unloadcollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/unloadcollection/leaders
[junit4:junit4]   2> 6809 T746 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6810 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0006 type:create cxid:0x52 zxid:0x5a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6812 T746 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6813 T746 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6813 T746 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50519/ayf/d/unloadcollection1/
[junit4:junit4]   2> 6814 T746 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6814 T746 oasc.SyncStrategy.syncToMe http://127.0.0.1:50519/ayf/d/unloadcollection1/ has no replicas
[junit4:junit4]   2> 6815 T746 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50519/ayf/d/unloadcollection1/
[junit4:junit4]   2> 6815 T746 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leaders/shard1
[junit4:junit4]   2> 6821 T715 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d690c09df0006 type:create cxid:0x5c zxid:0x5e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8057 T733 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8065 T792 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 8066 T732 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 8066 T778 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 8065 T750 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 8066 T737 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 8066 T764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 8077 T746 oasc.ZkController.register We are http://127.0.0.1:50519/ayf/d/unloadcollection1/ and leader is http://127.0.0.1:50519/ayf/d/unloadcollection1/
[junit4:junit4]   2> 8078 T746 oasc.ZkController.register No LogReplay needed for core=unloadcollection1 baseURL=http://127.0.0.1:50519/ayf/d
[junit4:junit4]   2> 8078 T746 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8078 T746 oasc.ZkController.publish publishing core=unloadcollection1 state=active
[junit4:junit4]   2> 8081 T746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8082 T746 oasc.CoreContainer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011/solr.xml
[junit4:junit4]   2> 8089 T746 oasc.SolrXMLSerializer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363267030011/solr.xml
[junit4:junit4]   2> 8091 T746 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {wt=javabin&numShards=1&version=2&collection=unloadcollection&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267034690unloadcollection1_1n&name=unloadcollection1&action=CREATE} status=0 QTime=2863 
[junit4:junit4]   2> 8092 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 8094 T710 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:41539/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@56696
[junit4:junit4]   2> 8095 T710 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8095 T795 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:41539. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8096 T795 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:41539, initiating session
[junit4:junit4]   2> 8096 T712 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:53801
[junit4:junit4]   2> 8096 T712 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:53801
[junit4:junit4]   2> 8099 T714 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d690c09df000d with negotiated timeout 10000 for client /127.0.0.1:53801
[junit4:junit4]   2> 8099 T795 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:41539, sessionid = 0x13d690c09df000d, negotiated timeout = 10000
[junit4:junit4]   2> 8100 T796 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@56696 name:ZooKeeperConnection Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8100 T710 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8102 T710 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8104 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8106 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8112 T760 oasc.CoreContainer.create Creating SolrCore 'unloadcollection2' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166/unloadcollection2
[junit4:junit4]   2> 8113 T760 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 8114 T760 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8114 T760 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 8116 T760 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166/unloadcollection2/'
[junit4:junit4]   2> 8163 T760 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 8216 T760 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8217 T760 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8223 T760 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8709 T760 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 8722 T760 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8725 T760 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8740 T760 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8744 T760 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8753 T760 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8755 T760 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 8756 T760 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 8756 T760 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8758 T760 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 8758 T760 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 8759 T760 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 8759 T760 oasc.SolrCore.<init> [unloadcollection2] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166/unloadcollection2/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267037574unloadcollection1_2n/
[junit4:junit4]   2> 8759 T760 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e3ada9
[junit4:junit4]   2> 8760 T760 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 8761 T760 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267037574unloadcollection1_2n forceNew: false
[junit4:junit4]   2> 8761 T760 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267037574unloadcollection1_2n/index/
[junit4:junit4]   2> 8762 T760 oasc.SolrCore.initIndex WARNING [unloadcollection2] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267037574unloadcollection1_2n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8762 T760 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267037574unloadcollection1_2n/index forceNew: false
[junit4:junit4]   2> 8764 T760 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@70aa0b lockFactory=org.apache.lucene.store.NativeFSLockFactory@87f246),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8765 T760 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8767 T760 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 8768 T760 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 8768 T760 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 8769 T760 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8770 T760 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8770 T760 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 8771 T760 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 8771 T760 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8772 T760 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8775 T760 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8779 T760 oass.SolrIndexSearcher.<init> Opening Searcher@3d6bb5 main
[junit4:junit4]   2> 8779 T760 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267037574unloadcollection1_2n/tlog
[junit4:junit4]   2> 8780 T760 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8781 T760 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8784 T797 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@3d6bb5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8785 T760 oasc.ZkController.publish publishing core=unloadcollection2 state=down
[junit4:junit4]   2> 8785 T760 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9572 T733 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9573 T733 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50519/ayf/d",
[junit4:junit4]   2> 	  "core":"unloadcollection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50519_ayf%2Fd"}
[junit4:junit4]   2> 9577 T733 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34239/ayf/d",
[junit4:junit4]   2> 	  "core":"unloadcollection2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34239_ayf%2Fd"}
[junit4:junit4]   2> 9578 T733 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 9578 T733 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 9582 T792 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 9582 T796 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 9582 T764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 9582 T750 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 9582 T778 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 9583 T737 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 9582 T732 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 9787 T760 oasc.CoreContainer.registerCore registering core: unloadcollection2
[junit4:junit4]   2> 9788 T760 oasc.ZkController.register Register replica - core:unloadcollection2 address:http://127.0.0.1:34239/ayf/d collection:unloadcollection shard:shard1
[junit4:junit4]   2> 9791 T760 oasc.ZkController.register We are http://127.0.0.1:34239/ayf/d/unloadcollection2/ and leader is http://127.0.0.1:50519/ayf/d/unloadcollection1/
[junit4:junit4]   2> 9792 T760 oasc.ZkController.register No LogReplay needed for core=unloadcollection2 baseURL=http://127.0.0.1:34239/ayf/d
[junit4:junit4]   2> 9792 T760 oasc.ZkController.checkRecovery Core needs to recover:unloadcollection2
[junit4:junit4]   2> 9792 T760 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> 9799 T760 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9801 T760 oasc.CoreContainer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166/solr.xml
[junit4:junit4]   2> ASYNC  NEW_CORE C36 name=unloadcollection2 org.apache.solr.core.SolrCore@2ea323 url=http://127.0.0.1:34239/ayf/d/unloadcollection2 node=127.0.0.1:34239_ayf%2Fd C36_STATE=coll:unloadcollection core:unloadcollection2 props:{state=down, collection=unloadcollection, base_url=http://127.0.0.1:34239/ayf/d, core=unloadcollection2, node_name=127.0.0.1:34239_ayf%2Fd}
[junit4:junit4]   2> 9806 T798 C36 P34239 oasc.RecoveryStrategy.run Starting recovery process.  core=unloadcollection2 recoveringAfterStartup=true
[junit4:junit4]   2> 9808 T798 C36 P34239 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 9808 T798 C36 P34239 oasc.ZkController.publish publishing core=unloadcollection2 state=recovering
[junit4:junit4]   2> 9808 T798 C36 P34239 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9809 T760 oasc.SolrXMLSerializer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363267031166/solr.xml
[junit4:junit4]   2> 9810 T798 C36 P34239 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9810 T760 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {wt=javabin&version=2&collection=unloadcollection&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267037574unloadcollection1_2n&name=unloadcollection2&action=CREATE} status=0 QTime=1698 
[junit4:junit4]   2> 9812 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9813 T710 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: unloadcollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 9814 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10816 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11090 T733 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11091 T733 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34239/ayf/d",
[junit4:junit4]   2> 	  "core":"unloadcollection2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34239_ayf%2Fd"}
[junit4:junit4]   2> 11111 T778 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 11111 T796 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 11111 T750 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 11111 T737 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 11111 T732 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 11111 T792 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 11111 T764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 11818 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11819 T746 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {wt=javabin&checkLive=true&state=recovering&coreNodeName=127.0.0.1:34239_ayf%252Fd_unloadcollection2&nodeName=127.0.0.1:34239_ayf%252Fd&version=2&core=unloadcollection1&action=PREPRECOVERY&onlyIfLeader=true} status=0 QTime=2001 
[junit4:junit4]   2> 12819 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C36_STATE=coll:unloadcollection core:unloadcollection2 props:{shard=shard1, state=recovering, collection=unloadcollection, base_url=http://127.0.0.1:34239/ayf/d, core=unloadcollection2, node_name=127.0.0.1:34239_ayf%2Fd}
[junit4:junit4]   2> 13820 T798 C36 P34239 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:50519/ayf/d/unloadcollection1/ core=unloadcollection2 - recoveringAfterStartup=true
[junit4:junit4]   2> 13821 T798 C36 P34239 oasu.PeerSync.sync PeerSync: core=unloadcollection2 url=http://127.0.0.1:34239/ayf/d START replicas=[http://127.0.0.1:50519/ayf/d/unloadcollection1/] nUpdates=100
[junit4:junit4]   2> 13821 T798 C36 P34239 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 13821 T798 C36 P34239 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=unloadcollection2
[junit4:junit4]   2> 13822 T798 C36 P34239 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=unloadcollection2
[junit4:junit4]   2> 13822 T798 C36 P34239 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=unloadcollection2
[junit4:junit4]   2> 13822 T798 C36 P34239 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 13822 T798 C36 P34239 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:50519/ayf/d/unloadcollection1/. core=unloadcollection2
[junit4:junit4]   2> 13823 T798 C36 P34239 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 13823 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C37 name=unloadcollection1 org.apache.solr.core.SolrCore@12f68b7 url=http://127.0.0.1:50519/ayf/d/unloadcollection1 node=127.0.0.1:50519_ayf%2Fd C37_STATE=coll:unloadcollection core:unloadcollection1 props:{shard=shard1, state=active, collection=unloadcollection, base_url=http://127.0.0.1:50519/ayf/d, core=unloadcollection1, node_name=127.0.0.1:50519_ayf%2Fd, leader=true}
[junit4:junit4]   2> 13837 T746 C37 P50519 REQ /get {wt=javabin&distrib=false&version=2&getVersions=100&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 13848 T743 C37 P50519 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 13850 T743 C37 P50519 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e50243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@18372e2),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13851 T743 C37 P50519 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 13852 T743 C37 P50519 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e50243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@18372e2),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e50243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@18372e2),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 13852 T743 C37 P50519 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 13853 T743 C37 P50519 oass.SolrIndexSearcher.<init> Opening Searcher@15f1a0a realtime
[junit4:junit4]   2> 13854 T743 C37 P50519 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13854 T743 C37 P50519 /update {wt=javabin&commit=true&commit_end_point=true&openSearcher=false&version=2&waitSearcher=true&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 13855 T798 C36 P34239 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 13856 T798 C36 P34239 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 13863 T798 C36 P34239 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 13868 T745 C37 P50519 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 13868 T745 C37 P50519 REQ /replication {wt=javabin&version=2&qt=/replication&command=indexversion} status=0 QTime=4 
[junit4:junit4]   2> 13870 T798 C36 P34239 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 13870 T798 C36 P34239 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 13870 T798 C36 P34239 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 13873 T746 C37 P50519 REQ /replication {wt=javabin&generation=2&version=2&qt=/replication&command=filelist} status=0 QTime=0 
[junit4:junit4]   2> 13874 T798 C36 P34239 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 13876 T798 C36 P34239 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267037574unloadcollection1_2n/index.20130314091723339 forceNew: false
[junit4:junit4]   2> 13876 T798 C36 P34239 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@10bb341 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8d4b10) fullCopy=false
[junit4:junit4]   2> 13884 T743 C37 P50519 REQ /replication {wt=filestream&generation=2&file=segments_2&qt=/replication&checksum=true&command=filecontent} status=0 QTime=2 
[junit4:junit4]   2> 13887 T798 C36 P34239 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 13888 T798 C36 P34239 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 13889 T798 C36 P34239 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=unloadcollection2
[junit4:junit4]   2> 13890 T798 C36 P34239 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@70aa0b lockFactory=org.apache.lucene.store.NativeFSLockFactory@87f246),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@70aa0b lockFactory=org.apache.lucene.store.NativeFSLockFactory@87f246),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 13891 T798 C36 P34239 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 13891 T798 C36 P34239 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 13892 T798 C36 P34239 oass.SolrIndexSearcher.<init> Opening Searcher@3e4caf main
[junit4:junit4]   2> 13893 T797 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@3e4caf main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 13893 T798 C36 P34239 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@10bb341 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8d4b10)
[junit4:junit4]   2> 13894 T798 C36 P34239 oasc.RecoveryStrategy.replay No replay needed. core=unloadcollection2
[junit4:junit4]   2> 13894 T798 C36 P34239 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=unloadcollection2
[junit4:junit4]   2> 13894 T798 C36 P34239 oasc.ZkController.publish publishing core=unloadcollection2 state=active
[junit4:junit4]   2> 13895 T798 C36 P34239 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 13896 T798 C36 P34239 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=unloadcollection2
[junit4:junit4]   2> 14120 T733 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14121 T733 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34239/ayf/d",
[junit4:junit4]   2> 	  "core":"unloadcollection2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34239_ayf%2Fd"}
[junit4:junit4]   2> 14126 T750 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 14127 T792 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 14127 T737 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 14126 T796 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 14127 T764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 14127 T778 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 14127 T732 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 14834 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14835 T710 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: unloadcollection
[junit4:junit4]   2> 14836 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 14840 T773 oasc.CoreContainer.create Creating SolrCore 'unloadcollection3' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330/unloadcollection3
[junit4:junit4]   2> 14841 T773 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 14842 T773 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 14842 T773 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 14844 T773 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330/unloadcollection3/'
[junit4:junit4]   2> 14877 T773 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 14915 T773 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 14916 T773 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 14922 T773 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 15407 T773 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 15416 T773 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 15420 T773 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 15442 T773 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 15446 T773 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 15451 T773 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 15453 T773 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 15454 T773 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 15454 T773 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 15456 T773 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 15456 T773 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 15457 T773 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 15458 T773 oasc.SolrCore.<init> [unloadcollection3] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330/unloadcollection3/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267044303unloadcollection_3n/
[junit4:junit4]   2> 15458 T773 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e3ada9
[junit4:junit4]   2> 15459 T773 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 15460 T773 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267044303unloadcollection_3n forceNew: false
[junit4:junit4]   2> 15461 T773 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267044303unloadcollection_3n/index/
[junit4:junit4]   2> 15461 T773 oasc.SolrCore.initIndex WARNING [unloadcollection3] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267044303unloadcollection_3n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 15463 T773 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267044303unloadcollection_3n/index forceNew: false
[junit4:junit4]   2> 15465 T773 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5364d0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce42d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 15466 T773 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 15468 T773 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 15469 T773 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 15469 T773 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 15470 T773 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 15471 T773 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 15471 T773 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 15472 T773 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 15473 T773 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 15473 T773 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 15482 T773 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 15486 T773 oass.SolrIndexSearcher.<init> Opening Searcher@138f97b main
[junit4:junit4]   2> 15486 T773 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267044303unloadcollection_3n/tlog
[junit4:junit4]   2> 15487 T773 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 15488 T773 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 15492 T801 oasc.SolrCore.registerSearcher [unloadcollection3] Registered new searcher Searcher@138f97b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 15494 T773 oasc.ZkController.publish publishing core=unloadcollection3 state=down
[junit4:junit4]   2> 15494 T773 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15638 T733 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15639 T733 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:45146/ayf/d",
[junit4:junit4]   2> 	  "core":"unloadcollection3",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:45146_ayf%2Fd"}
[junit4:junit4]   2> 15640 T733 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 15640 T733 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 15681 T737 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15681 T778 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15681 T792 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15681 T732 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15681 T750 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15681 T764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15681 T796 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 16499 T773 oasc.CoreContainer.registerCore registering core: unloadcollection3
[junit4:junit4]   2> 16499 T773 oasc.ZkController.register Register replica - core:unloadcollection3 address:http://127.0.0.1:45146/ayf/d collection:unloadcollection shard:shard1
[junit4:junit4]   2> 16504 T773 oasc.ZkController.register We are http://127.0.0.1:45146/ayf/d/unloadcollection3/ and leader is http://127.0.0.1:50519/ayf/d/unloadcollection1/
[junit4:junit4]   2> 16505 T773 oasc.ZkController.register No LogReplay needed for core=unloadcollection3 baseURL=http://127.0.0.1:45146/ayf/d
[junit4:junit4]   2> 16505 T773 oasc.ZkController.checkRecovery Core needs to recover:unloadcollection3
[junit4:junit4]   2> 16505 T773 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> 16507 T773 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C38 name=unloadcollection3 org.apache.solr.core.SolrCore@1ebb492 url=http://127.0.0.1:45146/ayf/d/unloadcollection3 node=127.0.0.1:45146_ayf%2Fd C38_STATE=coll:unloadcollection core:unloadcollection3 props:{state=down, collection=unloadcollection, base_url=http://127.0.0.1:45146/ayf/d, core=unloadcollection3, node_name=127.0.0.1:45146_ayf%2Fd}
[junit4:junit4]   2> 16508 T802 C38 P45146 oasc.RecoveryStrategy.run Starting recovery process.  core=unloadcollection3 recoveringAfterStartup=true
[junit4:junit4]   2> 16509 T773 oasc.CoreContainer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330/solr.xml
[junit4:junit4]   2> 16519 T802 C38 P45146 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 16520 T802 C38 P45146 oasc.ZkController.publish publishing core=unloadcollection3 state=recovering
[junit4:junit4]   2> 16520 T802 C38 P45146 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16521 T802 C38 P45146 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16527 T773 oasc.SolrXMLSerializer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363267032330/solr.xml
[junit4:junit4]   2> 16528 T773 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {wt=javabin&version=2&collection=unloadcollection&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267044303unloadcollection_3n&name=unloadcollection3&action=CREATE} status=0 QTime=1688 
[junit4:junit4]   2> 16529 T710 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: unloadcollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 16531 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17188 T733 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17192 T733 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:45146/ayf/d",
[junit4:junit4]   2> 	  "core":"unloadcollection3",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:45146_ayf%2Fd"}
[junit4:junit4]   2> 17196 T764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 17196 T796 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 17196 T778 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 17196 T737 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 17197 T792 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 17198 T750 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 17199 T732 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 17531 T744 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {wt=javabin&checkLive=true&state=recovering&coreNodeName=127.0.0.1:45146_ayf%252Fd_unloadcollection3&nodeName=127.0.0.1:45146_ayf%252Fd&version=2&core=unloadcollection1&action=PREPRECOVERY&onlyIfLeader=true} status=0 QTime=1000 
[junit4:junit4]   2> 17533 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18535 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C38_STATE=coll:unloadcollection core:unloadcollection3 props:{shard=shard1, state=recovering, collection=unloadcollection, base_url=http://127.0.0.1:45146/ayf/d, core=unloadcollection3, node_name=127.0.0.1:45146_ayf%2Fd}
[junit4:junit4]   2> 19532 T802 C38 P45146 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:50519/ayf/d/unloadcollection1/ core=unloadcollection3 - recoveringAfterStartup=true
[junit4:junit4]   2> 19533 T802 C38 P45146 oasu.PeerSync.sync PeerSync: core=unloadcollection3 url=http://127.0.0.1:45146/ayf/d START replicas=[http://127.0.0.1:50519/ayf/d/unloadcollection1/] nUpdates=100
[junit4:junit4]   2> 19533 T802 C38 P45146 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 19533 T802 C38 P45146 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=unloadcollection3
[junit4:junit4]   2> 19534 T802 C38 P45146 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=unloadcollection3
[junit4:junit4]   2> 19534 T802 C38 P45146 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=unloadcollection3
[junit4:junit4]   2> 19534 T802 C38 P45146 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 19535 T802 C38 P45146 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:50519/ayf/d/unloadcollection1/. core=unloadcollection3
[junit4:junit4]   2> 19535 T802 C38 P45146 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 19536 T744 C37 P50519 REQ /get {wt=javabin&distrib=false&version=2&getVersions=100&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 19537 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19542 T743 C37 P50519 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 19542 T743 C37 P50519 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e50243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@18372e2),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e50243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@18372e2),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 19543 T743 C37 P50519 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 19543 T743 C37 P50519 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 19544 T743 C37 P50519 /update {wt=javabin&commit=true&commit_end_point=true&openSearcher=false&version=2&waitSearcher=true&softCommit=false} {commit=} 0 3
[junit4:junit4]   2> 19545 T802 C38 P45146 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19546 T802 C38 P45146 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 19549 T744 C37 P50519 REQ /replication {wt=javabin&version=2&qt=/replication&command=indexversion} status=0 QTime=1 
[junit4:junit4]   2> 19550 T802 C38 P45146 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4:junit4]   2> 19550 T802 C38 P45146 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 19550 T802 C38 P45146 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 19552 T745 C37 P50519 REQ /replication {wt=javabin&generation=3&version=2&qt=/replication&command=filelist} status=0 QTime=0 
[junit4:junit4]   2> 19553 T802 C38 P45146 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 19554 T802 C38 P45146 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363267029468/1363267044303unloadcollection_3n/index.20130314091729018 forceNew: false
[junit4:junit4]   2> 19554 T802 C38 P45146 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e43a1d lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fa966) fullCopy=false
[junit4:junit4]   2> 19557 T743 C37 P50519 REQ /replication {wt=filestream&generation=3&file=segments_3&qt=/replication&checksum=true&command=filecontent} status=0 QTime=0 
[junit4:junit4]   2> 19559 T802 C38 P45146 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 19560 T802 C38 P45146 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 19560 T802 C38 P45146 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=unloadcollection3
[junit4:junit4]   2> 19561 T802 C38 P45146 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5364d0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce42d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5364d0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7ce42d),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 19562 T802 C38 P45146 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 19562 T802 C38 P45146 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 19562 T802 C38 P45146 oass.SolrIndexSearcher.<init> Opening Searcher@177bd35 main
[junit4:junit4]   2> 19563 T801 oasc.SolrCore.registerSearcher [unloadcollection3] Registered new searcher Searcher@177bd35 main{StandardDirectoryReader(segments_3:1:nrt)}
[junit4:junit4]   2> 19564 T802 C38 P45146 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e43a1d lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fa966)
[junit4:junit4]   2> 19564 T802 C38 P45146 oasc.RecoveryStrategy.replay No replay needed. core=unloadcollection3
[junit4:junit4]   2> 19564 T802 C38 P45146 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=unloadcollection3
[junit4:junit4]   2> 19565 T802 C38 P45146 oasc.ZkController.publish publishing core=unloadcollection3 state=active
[junit4:junit4]   2> 19565 T802 C38 P45146 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 19567 T802 C38 P45146 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=unloadcollection3
[junit4:junit4]   2> 20206 T733 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20207 T733 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:45146/ayf/d",
[junit4:junit4]   2> 	  "core":"unloadcollection3",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:45146_ayf%2Fd"}
[junit4:junit4]   2> 20221 T792 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20221 T750 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20221 T778 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20221 T796 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20221 T764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20221 T737 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20221 T732 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20539 T710 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20540 T710 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: unloadcollection
[junit4:junit4]   2> 20541 T710 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2>  C38_STATE=coll:unloadcollection core:unloadcollection3 props:{shard=shard1, state=active, collection=unloadcollection, base_url=http://127.0.0.1:45146/ayf/d, core=unloadcollection3, node_name=127.0.0.1:45146_ayf%2Fd}
[junit4:junit4]   2> 20608 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[20 (1429489110272180224)]} 0 7
[junit4:junit4]   2>  C36_STATE=coll:unloadcollection core:unloadcollection2 props:{shard=shard1, state=active, collection=unloadcollection, base_url=http://127.0.0.1:34239/ayf/d, core=unloadcollection2, node_name=127.0.0.1:34239_ayf%2Fd}
[junit4:junit4]   2> 20631 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[20 (1429489110272180224)]} 0 3
[junit4:junit4]   2> 20632 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[20 (1429489110272180224)]} 0 47
[junit4:junit4]   2> 20633 T772 C38 P45146 /update {wt=javabin&version=2} {add=[20]} 0 82
[junit4:junit4]   2> 20644 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[21 (1429489110327754752)]} 0 2
[junit4:junit4]   2> 20644 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[21 (1429489110327754752)]} 0 2
[junit4:junit4]   2> 20646 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[21 (1429489110327754752)]} 0 8
[junit4:junit4]   2> 20647 T773 C38 P45146 /update {wt=javabin&version=2} {add=[21]} 0 11
[junit4:junit4]   2> 20658 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[22 (1429489110342434816)]} 0 2
[junit4:junit4]   2> 20658 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[22 (1429489110342434816)]} 0 1
[junit4:junit4]   2> 20660 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[22 (1429489110342434816)]} 0 8
[junit4:junit4]   2> 20661 T774 C38 P45146 /update {wt=javabin&version=2} {add=[22]} 0 12
[junit4:junit4]   2> 20672 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[23 (1429489110357114880)]} 0 2
[junit4:junit4]   2> 20672 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[23 (1429489110357114880)]} 0 2
[junit4:junit4]   2> 20673 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[23 (1429489110357114880)]} 0 7
[junit4:junit4]   2> 20674 T771 C38 P45146 /update {wt=javabin&version=2} {add=[23]} 0 10
[junit4:junit4]   2> 20685 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[24 (1429489110370746368)]} 0 1
[junit4:junit4]   2> 20685 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[24 (1429489110370746368)]} 0 1
[junit4:junit4]   2> 20688 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[24 (1429489110370746368)]} 0 9
[junit4:junit4]   2> 20689 T772 C38 P45146 /update {wt=javabin&version=2} {add=[24]} 0 12
[junit4:junit4]   2> 20699 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[25 (1429489110386475008)]} 0 1
[junit4:junit4]   2> 20701 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[25 (1429489110386475008)]} 0 2
[junit4:junit4]   2> 20702 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[25 (1429489110386475008)]} 0 8
[junit4:junit4]   2> 20702 T773 C38 P45146 /update {wt=javabin&version=2} {add=[25]} 0 11
[junit4:junit4]   2> 20713 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[26 (1429489110401155072)]} 0 2
[junit4:junit4]   2> 20714 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[26 (1429489110401155072)]} 0 2
[junit4:junit4]   2> 20714 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[26 (1429489110401155072)]} 0 6
[junit4:junit4]   2> 20715 T774 C38 P45146 /update {wt=javabin&version=2} {add=[26]} 0 10
[junit4:junit4]   2> 20725 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[27 (1429489110414786560)]} 0 1
[junit4:junit4]   2> 20728 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[27 (1429489110414786560)]} 0 2
[junit4:junit4]   2> 20729 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[27 (1429489110414786560)]} 0 9
[junit4:junit4]   2> 20730 T771 C38 P45146 /update {wt=javabin&version=2} {add=[27]} 0 12
[junit4:junit4]   2> 20740 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[28 (1429489110429466624)]} 0 1
[junit4:junit4]   2> 20742 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[28 (1429489110429466624)]} 0 2
[junit4:junit4]   2> 20742 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[28 (1429489110429466624)]} 0 7
[junit4:junit4]   2> 20743 T772 C38 P45146 /update {wt=javabin&version=2} {add=[28]} 0 10
[junit4:junit4]   2> 20753 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[29 (1429489110444146688)]} 0 1
[junit4:junit4]   2> 20753 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[29 (1429489110444146688)]} 0 1
[junit4:junit4]   2> 20755 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[29 (1429489110444146688)]} 0 7
[junit4:junit4]   2> 20755 T773 C38 P45146 /update {wt=javabin&version=2} {add=[29]} 0 9
[junit4:junit4]   2> 20766 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[30 (1429489110456729600)]} 0 2
[junit4:junit4]   2> 20766 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[30 (1429489110456729600)]} 0 2
[junit4:junit4]   2> 20767 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[30 (1429489110456729600)]} 0 6
[junit4:junit4]   2> 20768 T774 C38 P45146 /update {wt=javabin&version=2} {add=[30]} 0 10
[junit4:junit4]   2> 20779 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[31 (1429489110470361088)]} 0 2
[junit4:junit4]   2> 20779 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[31 (1429489110470361088)]} 0 2
[junit4:junit4]   2> 20781 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[31 (1429489110470361088)]} 0 8
[junit4:junit4]   2> 20781 T771 C38 P45146 /update {wt=javabin&version=2} {add=[31]} 0 10
[junit4:junit4]   2> 20794 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[32 (1429489110486089728)]} 0 2
[junit4:junit4]   2> 20796 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[32 (1429489110486089728)]} 0 1
[junit4:junit4]   2> 20797 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[32 (1429489110486089728)]} 0 8
[junit4:junit4]   2> 20798 T772 C38 P45146 /update {wt=javabin&version=2} {add=[32]} 0 14
[junit4:junit4]   2> 20811 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[33 (1429489110502866944)]} 0 2
[junit4:junit4]   2> 20811 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[33 (1429489110502866944)]} 0 2
[junit4:junit4]   2> 20812 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[33 (1429489110502866944)]} 0 7
[junit4:junit4]   2> 20813 T773 C38 P45146 /update {wt=javabin&version=2} {add=[33]} 0 10
[junit4:junit4]   2> 20823 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[34 (1429489110516498432)]} 0 1
[junit4:junit4]   2> 20823 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[34 (1429489110516498432)]} 0 1
[junit4:junit4]   2> 20824 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[34 (1429489110516498432)]} 0 6
[junit4:junit4]   2> 20825 T774 C38 P45146 /update {wt=javabin&version=2} {add=[34]} 0 9
[junit4:junit4]   2> 20836 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[35 (1429489110530129920)]} 0 2
[junit4:junit4]   2> 20836 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[35 (1429489110530129920)]} 0 2
[junit4:junit4]   2> 20837 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[35 (1429489110530129920)]} 0 7
[junit4:junit4]   2> 20838 T771 C38 P45146 /update {wt=javabin&version=2} {add=[35]} 0 10
[junit4:junit4]   2> 20848 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[36 (1429489110542712832)]} 0 2
[junit4:junit4]   2> 20848 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[36 (1429489110542712832)]} 0 2
[junit4:junit4]   2> 20849 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[36 (1429489110542712832)]} 0 6
[junit4:junit4]   2> 20850 T772 C38 P45146 /update {wt=javabin&version=2} {add=[36]} 0 10
[junit4:junit4]   2> 20860 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[37 (1429489110555295744)]} 0 2
[junit4:junit4]   2> 20860 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[37 (1429489110555295744)]} 0 2
[junit4:junit4]   2> 20861 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[37 (1429489110555295744)]} 0 6
[junit4:junit4]   2> 20862 T773 C38 P45146 /update {wt=javabin&version=2} {add=[37]} 0 10
[junit4:junit4]   2> 20873 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[38 (1429489110568927232)]} 0 1
[junit4:junit4]   2> 20873 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[38 (1429489110568927232)]} 0 1
[junit4:junit4]   2> 20880 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[38 (1429489110568927232)]} 0 12
[junit4:junit4]   2> 20881 T774 C38 P45146 /update {wt=javabin&version=2} {add=[38]} 0 16
[junit4:junit4]   2> 20892 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[39 (1429489110588850176)]} 0 2
[junit4:junit4]   2> 20892 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[39 (1429489110588850176)]} 0 2
[junit4:junit4]   2> 20893 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[39 (1429489110588850176)]} 0 6
[junit4:junit4]   2> 20894 T771 C38 P45146 /update {wt=javabin&version=2} {add=[39]} 0 10
[junit4:junit4]   2> 20904 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[40 (1429489110601433088)]} 0 2
[junit4:junit4]   2> 20904 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[40 (1429489110601433088)]} 0 2
[junit4:junit4]   2> 20905 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[40 (1429489110601433088)]} 0 6
[junit4:junit4]   2> 20906 T772 C38 P45146 /update {wt=javabin&version=2} {add=[40]} 0 9
[junit4:junit4]   2> 20916 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[41 (1429489110614016000)]} 0 2
[junit4:junit4]   2> 20916 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[41 (1429489110614016000)]} 0 1
[junit4:junit4]   2> 20917 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[41 (1429489110614016000)]} 0 6
[junit4:junit4]   2> 20918 T773 C38 P45146 /update {wt=javabin&version=2} {add=[41]} 0 10
[junit4:junit4]   2> 20928 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[42 (1429489110627647488)]} 0 1
[junit4:junit4]   2> 20929 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[42 (1429489110627647488)]} 0 2
[junit4:junit4]   2> 20929 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[42 (1429489110627647488)]} 0 6
[junit4:junit4]   2> 20934 T774 C38 P45146 /update {wt=javabin&version=2} {add=[42]} 0 13
[junit4:junit4]   2> 20945 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[43 (1429489110644424704)]} 0 1
[junit4:junit4]   2> 20945 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[43 (1429489110644424704)]} 0 1
[junit4:junit4]   2> 20947 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[43 (1429489110644424704)]} 0 7
[junit4:junit4]   2> 20948 T771 C38 P45146 /update {wt=javabin&version=2} {add=[43]} 0 10
[junit4:junit4]   2> 20959 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[44 (1429489110659104768)]} 0 1
[junit4:junit4]   2> 20959 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[44 (1429489110659104768)]} 0 1
[junit4:junit4]   2> 20961 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[44 (1429489110659104768)]} 0 8
[junit4:junit4]   2> 20961 T774 C38 P45146 /update {wt=javabin&version=2} {add=[44]} 0 11
[junit4:junit4]   2> 20972 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[45 (1429489110672736256)]} 0 2
[junit4:junit4]   2> 20978 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[45 (1429489110672736256)]} 0 1
[junit4:junit4]   2> 20979 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[45 (1429489110672736256)]} 0 12
[junit4:junit4]   2> 20980 T771 C38 P45146 /update {wt=javabin&version=2} {add=[45]} 0 16
[junit4:junit4]   2> 20990 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[46 (1429489110692659200)]} 0 1
[junit4:junit4]   2> 20990 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[46 (1429489110692659200)]} 0 1
[junit4:junit4]   2> 20992 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[46 (1429489110692659200)]} 0 7
[junit4:junit4]   2> 20993 T773 C38 P45146 /update {wt=javabin&version=2} {add=[46]} 0 10
[junit4:junit4]   2> 21003 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[47 (1429489110705242112)]} 0 2
[junit4:junit4]   2> 21003 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[47 (1429489110705242112)]} 0 2
[junit4:junit4]   2> 21004 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[47 (1429489110705242112)]} 0 6
[junit4:junit4]   2> 21005 T772 C38 P45146 /update {wt=javabin&version=2} {add=[47]} 0 10
[junit4:junit4]   2> 21016 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[48 (1429489110718873600)]} 0 2
[junit4:junit4]   2> 21016 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[48 (1429489110718873600)]} 0 2
[junit4:junit4]   2> 21017 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[48 (1429489110718873600)]} 0 7
[junit4:junit4]   2> 21018 T773 C38 P45146 /update {wt=javabin&version=2} {add=[48]} 0 10
[junit4:junit4]   2> 21029 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[49 (1429489110732505088)]} 0 2
[junit4:junit4]   2> 21029 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[49 (1429489110732505088)]} 0 2
[junit4:junit4]   2> 21030 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[49 (1429489110732505088)]} 0 7
[junit4:junit4]   2> 21031 T774 C38 P45146 /update {wt=javabin&version=2} {add=[49]} 0 10
[junit4:junit4]   2> 21042 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[50 (1429489110746136576)]} 0 1
[junit4:junit4]   2> 21043 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[50 (1429489110746136576)]} 0 1
[junit4:junit4]   2> 21044 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[50 (1429489110746136576)]} 0 7
[junit4:junit4]   2> 21045 T771 C38 P45146 /update {wt=javabin&version=2} {add=[50]} 0 11
[junit4:junit4]   2> 21055 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[51 (1429489110759768064)]} 0 1
[junit4:junit4]   2> 21055 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[51 (1429489110759768064)]} 0 1
[junit4:junit4]   2> 21056 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[51 (1429489110759768064)]} 0 6
[junit4:junit4]   2> 21057 T772 C38 P45146 /update {wt=javabin&version=2} {add=[51]} 0 9
[junit4:junit4]   2> 21068 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[52 (1429489110773399552)]} 0 1
[junit4:junit4]   2> 21070 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[52 (1429489110773399552)]} 0 4
[junit4:junit4]   2> 21071 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[52 (1429489110773399552)]} 0 8
[junit4:junit4]   2> 21072 T773 C38 P45146 /update {wt=javabin&version=2} {add=[52]} 0 12
[junit4:junit4]   2> 21082 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[53 (1429489110788079616)]} 0 1
[junit4:junit4]   2> 21082 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[53 (1429489110788079616)]} 0 1
[junit4:junit4]   2> 21084 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[53 (1429489110788079616)]} 0 7
[junit4:junit4]   2> 21084 T774 C38 P45146 /update {wt=javabin&version=2} {add=[53]} 0 9
[junit4:junit4]   2> 21095 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[54 (1429489110801711104)]} 0 1
[junit4:junit4]   2> 21095 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[54 (1429489110801711104)]} 0 1
[junit4:junit4]   2> 21096 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[54 (1429489110801711104)]} 0 6
[junit4:junit4]   2> 21097 T771 C38 P45146 /update {wt=javabin&version=2} {add=[54]} 0 10
[junit4:junit4]   2> 21108 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[55 (1429489110815342592)]} 0 2
[junit4:junit4]   2> 21108 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[55 (1429489110815342592)]} 0 2
[junit4:junit4]   2> 21109 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[55 (1429489110815342592)]} 0 6
[junit4:junit4]   2> 21110 T772 C38 P45146 /update {wt=javabin&version=2} {add=[55]} 0 10
[junit4:junit4]   2> 21140 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[56 (1429489110848897024)]} 0 2
[junit4:junit4]   2> 21142 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[56 (1429489110848897024)]} 0 1
[junit4:junit4]   2> 21143 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[56 (1429489110848897024)]} 0 9
[junit4:junit4]   2> 21144 T773 C38 P45146 /update {wt=javabin&version=2} {add=[56]} 0 31
[junit4:junit4]   2> 21156 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[57 (1429489110864625664)]} 0 2
[junit4:junit4]   2> 21156 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[57 (1429489110864625664)]} 0 2
[junit4:junit4]   2> 21158 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[57 (1429489110864625664)]} 0 9
[junit4:junit4]   2> 21159 T774 C38 P45146 /update {wt=javabin&version=2} {add=[57]} 0 12
[junit4:junit4]   2> 21170 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[58 (1429489110880354304)]} 0 2
[junit4:junit4]   2> 21170 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[58 (1429489110880354304)]} 0 2
[junit4:junit4]   2> 21171 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[58 (1429489110880354304)]} 0 7
[junit4:junit4]   2> 21172 T771 C38 P45146 /update {wt=javabin&version=2} {add=[58]} 0 10
[junit4:junit4]   2> 21184 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[59 (1429489110895034368)]} 0 2
[junit4:junit4]   2> 21184 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[59 (1429489110895034368)]} 0 2
[junit4:junit4]   2> 21185 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[59 (1429489110895034368)]} 0 6
[junit4:junit4]   2> 21186 T772 C38 P45146 /update {wt=javabin&version=2} {add=[59]} 0 11
[junit4:junit4]   2> 21196 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[60 (1429489110907617280)]} 0 2
[junit4:junit4]   2> 21199 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[60 (1429489110907617280)]} 0 1
[junit4:junit4]   2> 21200 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[60 (1429489110907617280)]} 0 9
[junit4:junit4]   2> 21201 T773 C38 P45146 /update {wt=javabin&version=2} {add=[60]} 0 12
[junit4:junit4]   2> 21213 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[61 (1429489110924394496)]} 0 3
[junit4:junit4]   2> 21215 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[61 (1429489110924394496)]} 0 3
[junit4:junit4]   2> 21216 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[61 (1429489110924394496)]} 0 10
[junit4:junit4]   2> 21217 T774 C38 P45146 /update {wt=javabin&version=2} {add=[61]} 0 13
[junit4:junit4]   2> 21227 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[62 (1429489110940123136)]} 0 2
[junit4:junit4]   2> 21227 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[62 (1429489110940123136)]} 0 1
[junit4:junit4]   2> 21228 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[62 (1429489110940123136)]} 0 6
[junit4:junit4]   2> 21229 T771 C38 P45146 /update {wt=javabin&version=2} {add=[62]} 0 10
[junit4:junit4]   2> 21239 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[63 (1429489110953754624)]} 0 1
[junit4:junit4]   2> 21242 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[63 (1429489110953754624)]} 0 1
[junit4:junit4]   2> 21243 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[63 (1429489110953754624)]} 0 9
[junit4:junit4]   2> 21244 T772 C38 P45146 /update {wt=javabin&version=2} {add=[63]} 0 12
[junit4:junit4]   2> 21253 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[64 (1429489110968434688)]} 0 1
[junit4:junit4]   2> 21253 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[64 (1429489110968434688)]} 0 1
[junit4:junit4]   2> 21254 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[64 (1429489110968434688)]} 0 5
[junit4:junit4]   2> 21255 T773 C38 P45146 /update {wt=javabin&version=2} {add=[64]} 0 9
[junit4:junit4]   2> 21268 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[65 (1429489110981017600)]} 0 1
[junit4:junit4]   2> 21270 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[65 (1429489110981017600)]} 0 4
[junit4:junit4]   2> 21271 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[65 (1429489110981017600)]} 0 10
[junit4:junit4]   2> 21272 T774 C38 P45146 /update {wt=javabin&version=2} {add=[65]} 0 14
[junit4:junit4]   2> 21282 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[66 (1429489110997794816)]} 0 1
[junit4:junit4]   2> 21285 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[66 (1429489110997794816)]} 0 2
[junit4:junit4]   2> 21287 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[66 (1429489110997794816)]} 0 10
[junit4:junit4]   2> 21292 T771 C38 P45146 /update {wt=javabin&version=2} {add=[66]} 0 17
[junit4:junit4]   2> 21308 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[67 (1429489111025057792)]} 0 2
[junit4:junit4]   2> 21308 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[67 (1429489111025057792)]} 0 2
[junit4:junit4]   2> 21309 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[67 (1429489111025057792)]} 0 6
[junit4:junit4]   2> 21310 T772 C38 P45146 /update {wt=javabin&version=2} {add=[67]} 0 10
[junit4:junit4]   2> 21321 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[68 (1429489111038689280)]} 0 2
[junit4:junit4]   2> 21321 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[68 (1429489111038689280)]} 0 2
[junit4:junit4]   2> 21322 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[68 (1429489111038689280)]} 0 7
[junit4:junit4]   2> 21323 T773 C38 P45146 /update {wt=javabin&version=2} {add=[68]} 0 10
[junit4:junit4]   2> 21333 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[69 (1429489111052320768)]} 0 1
[junit4:junit4]   2> 21336 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[69 (1429489111052320768)]} 0 2
[junit4:junit4]   2> 21336 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[69 (1429489111052320768)]} 0 8
[junit4:junit4]   2> 21337 T774 C38 P45146 /update {wt=javabin&version=2} {add=[69]} 0 11
[junit4:junit4]   2> 21348 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[70 (1429489111067000832)]} 0 1
[junit4:junit4]   2> 21350 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[70 (1429489111067000832)]} 0 1
[junit4:junit4]   2> 21351 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[70 (1429489111067000832)]} 0 8
[junit4:junit4]   2> 21352 T771 C38 P45146 /update {wt=javabin&version=2} {add=[70]} 0 12
[junit4:junit4]   2> 21363 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[71 (1429489111082729472)]} 0 2
[junit4:junit4]   2> 21363 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[71 (1429489111082729472)]} 0 2
[junit4:junit4]   2> 21364 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[71 (1429489111082729472)]} 0 7
[junit4:junit4]   2> 21365 T774 C38 P45146 /update {wt=javabin&version=2} {add=[71]} 0 10
[junit4:junit4]   2> 21376 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[72 (1429489111096360960)]} 0 1
[junit4:junit4]   2> 21376 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[72 (1429489111096360960)]} 0 2
[junit4:junit4]   2> 21377 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[72 (1429489111096360960)]} 0 7
[junit4:junit4]   2> 21378 T771 C38 P45146 /update {wt=javabin&version=2} {add=[72]} 0 10
[junit4:junit4]   2> 21388 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[73 (1429489111108943872)]} 0 1
[junit4:junit4]   2> 21388 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[73 (1429489111108943872)]} 0 1
[junit4:junit4]   2> 21389 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[73 (1429489111108943872)]} 0 6
[junit4:junit4]   2> 21390 T773 C38 P45146 /update {wt=javabin&version=2} {add=[73]} 0 9
[junit4:junit4]   2> 21400 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[74 (1429489111122575360)]} 0 1
[junit4:junit4]   2> 21403 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[74 (1429489111122575360)]} 0 1
[junit4:junit4]   2> 21404 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[74 (1429489111122575360)]} 0 9
[junit4:junit4]   2> 21405 T772 C38 P45146 /update {wt=javabin&version=2} {add=[74]} 0 12
[junit4:junit4]   2> 21415 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[75 (1429489111138304000)]} 0 1
[junit4:junit4]   2> 21415 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[75 (1429489111138304000)]} 0 1
[junit4:junit4]   2> 21420 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[75 (1429489111138304000)]} 0 10
[junit4:junit4]   2> 21421 T774 C38 P45146 /update {wt=javabin&version=2} {add=[75]} 0 13
[junit4:junit4]   2> 21431 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[76 (1429489111155081216)]} 0 1
[junit4:junit4]   2> 21433 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[76 (1429489111155081216)]} 0 2
[junit4:junit4]   2> 21433 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[76 (1429489111155081216)]} 0 7
[junit4:junit4]   2> 21434 T772 C38 P45146 /update {wt=javabin&version=2} {add=[76]} 0 10
[junit4:junit4]   2> 21444 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[77 (1429489111168712704)]} 0 1
[junit4:junit4]   2> 21444 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[77 (1429489111168712704)]} 0 1
[junit4:junit4]   2> 21445 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[77 (1429489111168712704)]} 0 6
[junit4:junit4]   2> 21446 T773 C38 P45146 /update {wt=javabin&version=2} {add=[77]} 0 9
[junit4:junit4]   2> 21456 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[78 (1429489111181295616)]} 0 1
[junit4:junit4]   2> 21456 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[78 (1429489111181295616)]} 0 1
[junit4:junit4]   2> 21458 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[78 (1429489111181295616)]} 0 7
[junit4:junit4]   2> 21458 T771 C38 P45146 /update {wt=javabin&version=2} {add=[78]} 0 9
[junit4:junit4]   2> 21469 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[79 (1429489111193878528)]} 0 2
[junit4:junit4]   2> 21470 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[79 (1429489111193878528)]} 0 1
[junit4:junit4]   2> 21471 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[79 (1429489111193878528)]} 0 8
[junit4:junit4]   2> 21471 T774 C38 P45146 /update {wt=javabin&version=2} {add=[79]} 0 10
[junit4:junit4]   2> 21482 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[80 (1429489111207510016)]} 0 1
[junit4:junit4]   2> 21482 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[80 (1429489111207510016)]} 0 1
[junit4:junit4]   2> 21483 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[80 (1429489111207510016)]} 0 6
[junit4:junit4]   2> 21484 T772 C38 P45146 /update {wt=javabin&version=2} {add=[80]} 0 10
[junit4:junit4]   2> 21498 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[81 (1429489111221141504)]} 0 2
[junit4:junit4]   2> 21498 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[81 (1429489111221141504)]} 0 2
[junit4:junit4]   2> 21499 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[81 (1429489111221141504)]} 0 10
[junit4:junit4]   2> 21500 T773 C38 P45146 /update {wt=javabin&version=2} {add=[81]} 0 13
[junit4:junit4]   2> 21510 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[82 (1429489111237918720)]} 0 1
[junit4:junit4]   2> 21510 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[82 (1429489111237918720)]} 0 1
[junit4:junit4]   2> 21512 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[82 (1429489111237918720)]} 0 7
[junit4:junit4]   2> 21512 T771 C38 P45146 /update {wt=javabin&version=2} {add=[82]} 0 9
[junit4:junit4]   2> 21523 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[83 (1429489111250501632)]} 0 2
[junit4:junit4]   2> 21526 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[83 (1429489111250501632)]} 0 4
[junit4:junit4]   2> 21527 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[83 (1429489111250501632)]} 0 10
[junit4:junit4]   2> 21528 T774 C38 P45146 /update {wt=javabin&version=2} {add=[83]} 0 13
[junit4:junit4]   2> 21539 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[84 (1429489111267278848)]} 0 2
[junit4:junit4]   2> 21540 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[84 (1429489111267278848)]} 0 1
[junit4:junit4]   2> 21545 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[84 (1429489111267278848)]} 0 12
[junit4:junit4]   2> 21546 T772 C38 P45146 /update {wt=javabin&version=2} {add=[84]} 0 15
[junit4:junit4]   2> 21557 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[85 (1429489111287201792)]} 0 1
[junit4:junit4]   2> 21560 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[85 (1429489111287201792)]} 0 2
[junit4:junit4]   2> 21561 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[85 (1429489111287201792)]} 0 9
[junit4:junit4]   2> 21562 T773 C38 P45146 /update {wt=javabin&version=2} {add=[85]} 0 13
[junit4:junit4]   2> 21572 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[86 (1429489111301881856)]} 0 1
[junit4:junit4]   2> 21573 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[86 (1429489111301881856)]} 0 2
[junit4:junit4]   2> 21574 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[86 (1429489111301881856)]} 0 7
[junit4:junit4]   2> 21574 T771 C38 P45146 /update {wt=javabin&version=2} {add=[86]} 0 10
[junit4:junit4]   2> 21589 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[87 (1429489111320756224)]} 0 1
[junit4:junit4]   2> 21592 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[87 (1429489111320756224)]} 0 1
[junit4:junit4]   2> 21593 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[87 (1429489111320756224)]} 0 9
[junit4:junit4]   2> 21594 T774 C38 P45146 /update {wt=javabin&version=2} {add=[87]} 0 12
[junit4:junit4]   2> 21606 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[88 (1429489111336484864)]} 0 3
[junit4:junit4]   2> 21607 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[88 (1429489111336484864)]} 0 3
[junit4:junit4]   2> 21608 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[88 (1429489111336484864)]} 0 9
[junit4:junit4]   2> 21609 T772 C38 P45146 /update {wt=javabin&version=2} {add=[88]} 0 12
[junit4:junit4]   2> 21620 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[89 (1429489111351164928)]} 0 2
[junit4:junit4]   2> 21622 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[89 (1429489111351164928)]} 0 3
[junit4:junit4]   2> 21623 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[89 (1429489111351164928)]} 0 9
[junit4:junit4]   2> 21623 T773 C38 P45146 /update {wt=javabin&version=2} {add=[89]} 0 12
[junit4:junit4]   2> 21634 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[90 (1429489111366893568)]} 0 1
[junit4:junit4]   2> 21634 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[90 (1429489111366893568)]} 0 1
[junit4:junit4]   2> 21635 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[90 (1429489111366893568)]} 0 6
[junit4:junit4]   2> 21636 T771 C38 P45146 /update {wt=javabin&version=2} {add=[90]} 0 10
[junit4:junit4]   2> 21647 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[91 (1429489111379476480)]} 0 2
[junit4:junit4]   2> 21649 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[91 (1429489111379476480)]} 0 3
[junit4:junit4]   2> 21649 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[91 (1429489111379476480)]} 0 8
[junit4:junit4]   2> 21650 T774 C38 P45146 /update {wt=javabin&version=2} {add=[91]} 0 11
[junit4:junit4]   2> 21660 T771 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[92 (1429489111395205120)]} 0 1
[junit4:junit4]   2> 21663 T760 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[92 (1429489111395205120)]} 0 1
[junit4:junit4]   2> 21664 T744 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[92 (1429489111395205120)]} 0 9
[junit4:junit4]   2> 21665 T772 C38 P45146 /update {wt=javabin&version=2} {add=[92]} 0 12
[junit4:junit4]   2> 21676 T774 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[93 (1429489111410933760)]} 0 2
[junit4:junit4]   2> 21678 T754 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[93 (1429489111410933760)]} 0 3
[junit4:junit4]   2> 21679 T746 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[93 (1429489111410933760)]} 0 9
[junit4:junit4]   2> 21679 T773 C38 P45146 /update {wt=javabin&version=2} {add=[93]} 0 11
[junit4:junit4]   2> 21690 T772 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[94 (1429489111425613824)]} 0 2
[junit4:junit4]   2> 21690 T759 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[94 (1429489111425613824)]} 0 2
[junit4:junit4]   2> 21691 T745 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[94 (1429489111425613824)]} 0 7
[junit4:junit4]   2> 21692 T771 C38 P45146 /update {wt=javabin&version=2} {add=[94]} 0 10
[junit4:junit4]   2> 21702 T758 C36 P34239 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[95 (1429489111438196736)]} 0 1
[junit4:junit4]   2> 21703 T773 C38 P45146 /update {update.distrib=FROMLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:50519/ayf/d/unloadcollection1/} {add=[95 (1429489111438196736)]} 0 1
[junit4:junit4]   2> 21704 T743 C37 P50519 /update {update.distrib=TOLEADER&wt=javabin&version=2&distrib.from=http://127.0.0.1:45146/ayf/d/unloadcollection3/} {add=[95 (1429489111438196736)]} 0 7
[junit4:junit4]   2> 21705 T774 C38 P45146 /update {wt=javabin&version=2} {add=[95]} 0 10
[junit4:junit4]   2> 2171

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

-n_0000000000 (0)
[junit4:junit4]   1>    /solr/overseer_elect/leader (0)
[junit4:junit4]   1>    DATA:
[junit4:junit4]   1>        {"id":"89343095664214019-127.0.0.1:54349_odde-n_0000000000"}
[junit4:junit4]   1> 
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZk2Test -Dtests.method=testDistribSearch -Dtests.seed=3DDFDC9DF034DEE9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_AR -Dtests.timezone=Australia/North -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   31.9s J0 | BasicDistributedZk2Test.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:41498/odde/onenodecollectioncore returned non ok status:404, message:Can not find: /odde/onenodecollectioncore/update
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([3DDFDC9DF034DEE9:BC395285876BBED5]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:487)
[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:46)
[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:49)
[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:46)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 31940 T2417 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 31943 T2416 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4]   2> 32075 T2420 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=DefaultSimilarity, locale=es_AR, timezone=Australia/North
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=2,free=192589824,total=304988160
[junit4:junit4]   2> NOTE: All tests run in this JVM: [PolyFieldTest, SuggesterFSTTest, DirectUpdateHandlerOptimizeTest, TestFieldTypeResource, CircularListTest, TestSolrDeletionPolicy1, SynonymTokenizerTest, ShowFileRequestHandlerTest, ResponseLogComponentTest, TestQueryUtils, LukeRequestHandlerTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, SearchHandlerTest, ClusterStateUpdateTest, DocumentBuilderTest, MinimalSchemaTest, FastVectorHighlighterTest, PrimitiveFieldTypeTest, TestConfig, SoftAutoCommitTest, TestFastLRUCache, PeerSyncTest, TestBadConfig, TermVectorComponentTest, TestSolrCoreProperties, TestXIncludeConfig, LeaderElectionTest, PrimUtilsTest, UUIDFieldTest, TestCoreContainer, CoreAdminHandlerTest, TestCharFilters, ReturnFieldsTest, DistributedSpellCheckComponentTest, ZkControllerTest, TestFuzzyAnalyzedSuggestions, ScriptEngineTest, SolrCoreTest, TestDynamicFieldResource, DirectSolrConnectionTest, TestFieldResource, BasicFunctionalityTest, TestIBSimilarityFactory, UnloadDistributedZkTest, OverseerTest, SOLR749Test, QueryElevationComponentTest, TestTrie, TestRemoteStreaming, AutoCommitTest, TestValueSourceCache, WordBreakSolrSpellCheckerTest, PreAnalyzedFieldTest, OverseerCollectionProcessorTest, QueryEqualityTest, TestReplicationHandler, DistributedQueryElevationComponentTest, TestPHPSerializedResponseWriter, TestReversedWildcardFilterFactory, BadComponentTest, TestExtendedDismaxParser, TestSolrIndexConfig, TestSort, DateFieldTest, TestPropInjectDefaults, AlternateDirectoryTest, BasicDistributedZkTest, BasicDistributedZk2Test]
[junit4:junit4] Completed on J0 in 32.21s, 1 test, 1 error <<< FAILURES!

[...truncated 417 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:361: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:183: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1213: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:877: There were test failures: 272 suites, 1145 tests, 1 error, 1 failure, 13 ignored (7 assumptions)

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



Mime
View raw message