You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/03/04 16:19:57 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b79) - Build #
4536 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4536/
Java: 32bit/jdk1.8.0-ea-b79 -server -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch
Error Message:
Server at http://127.0.0.1:52427/fph/onenodecollectioncore returned non ok status:404, message:Can not find: /fph/onenodecollectioncore/update
Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:52427/fph/onenodecollectioncore returned non ok status:404, message:Can not find: /fph/onenodecollectioncore/update
at __randomizedtesting.SeedInfo.seed([A7204E4A6F8C7F80:26C6C05218D31FBC]: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:85)
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)
FAILED: org.apache.solr.cloud.UnloadDistributedZkTest.testDistribSearch
Error Message:
Still found shard
Stack Trace:
java.lang.AssertionError: Still found shard
at __randomizedtesting.SeedInfo.seed([A7204E4A6F8C7F80:26C6C05218D31FBC]: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 9133 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest
[junit4:junit4] 2> 0 T522 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4] 2> 5 T522 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-1362409676150
[junit4:junit4] 2> 6 T522 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 7 T523 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 8 T523 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 8 T523 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 8 T523 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 8 T523 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 9 T523 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-1362409676150/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4] 2> 107 T522 oasc.ZkTestServer.run start zk server on port:36789
[junit4:junit4] 2> 107 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@8c615b
[junit4:junit4] 2> 108 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 108 T528 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 109 T528 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 109 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36689
[junit4:junit4] 2> 109 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36689
[junit4:junit4] 2> 110 T526 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 136 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0000 with negotiated timeout 10000 for client /127.0.0.1:36689
[junit4:junit4] 2> 136 T528 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0000, negotiated timeout = 10000
[junit4:junit4] 2> 136 T529 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8c615b name:ZooKeeperConnection Watcher:127.0.0.1:36789 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 137 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 137 T522 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 140 T527 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d35f1dd7a0000
[junit4:junit4] 2> 141 T529 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 141 T522 oaz.ZooKeeper.close Session: 0x13d35f1dd7a0000 closed
[junit4:junit4] 2> 141 T524 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36689 which had sessionid 0x13d35f1dd7a0000
[junit4:junit4] 2> 141 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4b897a
[junit4:junit4] 2> 142 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 142 T530 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 143 T530 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 143 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36690
[junit4:junit4] 2> 143 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36690
[junit4:junit4] 2> 144 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0001 with negotiated timeout 10000 for client /127.0.0.1:36690
[junit4:junit4] 2> 144 T530 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0001, negotiated timeout = 10000
[junit4:junit4] 2> 144 T531 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b897a name:ZooKeeperConnection Watcher:127.0.0.1:36789/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 144 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 144 T522 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 147 T522 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 153 T522 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 155 T522 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 159 T522 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> 159 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 165 T522 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> 166 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 173 T522 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> 174 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 177 T522 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> 178 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 181 T522 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> 182 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 185 T522 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> 186 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 189 T522 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> 189 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 192 T522 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> 193 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 196 T522 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> 196 T522 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 199 T527 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d35f1dd7a0001
[junit4:junit4] 2> 199 T531 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 200 T524 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36690 which had sessionid 0x13d35f1dd7a0001
[junit4:junit4] 2> 199 T522 oaz.ZooKeeper.close Session: 0x13d35f1dd7a0001 closed
[junit4:junit4] 2> 265 T522 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 268 T522 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51935
[junit4:junit4] 2> 268 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 269 T522 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 269 T522 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-1362409676344
[junit4:junit4] 2> 269 T522 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1362409676344/solr.xml
[junit4:junit4] 2> 269 T522 oasc.CoreContainer.<init> New CoreContainer 4263283
[junit4:junit4] 2> 270 T522 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-1362409676344/'
[junit4:junit4] 2> 270 T522 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-1362409676344/'
[junit4:junit4] 2> 295 T522 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 296 T522 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 296 T522 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 297 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 297 T522 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 298 T522 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 305 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 305 T522 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 305 T522 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 306 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 312 T522 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 321 T522 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36789/solr
[junit4:junit4] 2> 321 T522 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 322 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@178217a
[junit4:junit4] 2> 322 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 322 T541 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 323 T541 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 323 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36691
[junit4:junit4] 2> 324 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36691
[junit4:junit4] 2> 325 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0002 with negotiated timeout 20000 for client /127.0.0.1:36691
[junit4:junit4] 2> 325 T541 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0002, negotiated timeout = 20000
[junit4:junit4] 2> 325 T542 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@178217a name:ZooKeeperConnection Watcher:127.0.0.1:36789 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 325 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 326 T527 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d35f1dd7a0002
[junit4:junit4] 2> 327 T542 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 327 T524 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36691 which had sessionid 0x13d35f1dd7a0002
[junit4:junit4] 2> 327 T522 oaz.ZooKeeper.close Session: 0x13d35f1dd7a0002 closed
[junit4:junit4] 2> 327 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 330 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b0b7b2
[junit4:junit4] 2> 331 T543 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 332 T543 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 332 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36692
[junit4:junit4] 2> 332 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 332 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36692
[junit4:junit4] 2> 333 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0003 with negotiated timeout 20000 for client /127.0.0.1:36692
[junit4:junit4] 2> 333 T543 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0003, negotiated timeout = 20000
[junit4:junit4] 2> 333 T544 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b0b7b2 name:ZooKeeperConnection Watcher:127.0.0.1:36789/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 333 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 335 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 336 T522 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 338 T522 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51935_
[junit4:junit4] 2> 338 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51935_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51935_
[junit4:junit4] 2> 339 T522 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51935_
[junit4:junit4] 2> 341 T522 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 345 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0003 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> 346 T522 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 348 T522 oasc.Overseer.start Overseer (id=89286880536428547-127.0.0.1:51935_-n_0000000000) starting
[junit4:junit4] 2> 349 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 350 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 350 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 351 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 352 T546 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4] 2> 352 T522 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 355 T522 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 358 T545 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 360 T522 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 360 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 361 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 364 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 364 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6f5b54
[junit4:junit4] 2> 365 T547 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 365 T547 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 366 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36693
[junit4:junit4] 2> 366 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 366 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36693
[junit4:junit4] 2> 367 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0004 with negotiated timeout 10000 for client /127.0.0.1:36693
[junit4:junit4] 2> 367 T547 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0004, negotiated timeout = 10000
[junit4:junit4] 2> 367 T548 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f5b54 name:ZooKeeperConnection Watcher:127.0.0.1:36789/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 367 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 368 T522 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 369 T522 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 424 T522 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 427 T522 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:33124
[junit4:junit4] 2> 428 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 428 T522 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 429 T522 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-1362409676513
[junit4:junit4] 2> 429 T522 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1362409676513/solr.xml
[junit4:junit4] 2> 430 T522 oasc.CoreContainer.<init> New CoreContainer 6627751
[junit4:junit4] 2> 431 T522 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-1362409676513/'
[junit4:junit4] 2> 431 T522 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-1362409676513/'
[junit4:junit4] 2> 457 T522 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 457 T522 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 458 T522 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 458 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 459 T522 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 459 T522 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 460 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 460 T522 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 460 T522 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 461 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 468 T522 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 483 T522 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36789/solr
[junit4:junit4] 2> 484 T522 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 484 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f3eeee
[junit4:junit4] 2> 485 T558 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 485 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 485 T558 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 486 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36694
[junit4:junit4] 2> 486 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36694
[junit4:junit4] 2> 488 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0005 with negotiated timeout 20000 for client /127.0.0.1:36694
[junit4:junit4] 2> 488 T558 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0005, negotiated timeout = 20000
[junit4:junit4] 2> 488 T559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f3eeee name:ZooKeeperConnection Watcher:127.0.0.1:36789 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 488 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 489 T527 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d35f1dd7a0005
[junit4:junit4] 2> 490 T559 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 490 T524 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36694 which had sessionid 0x13d35f1dd7a0005
[junit4:junit4] 2> 490 T522 oaz.ZooKeeper.close Session: 0x13d35f1dd7a0005 closed
[junit4:junit4] 2> 490 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 493 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1275e3e
[junit4:junit4] 2> 494 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 494 T560 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 494 T560 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 494 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36695
[junit4:junit4] 2> 495 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36695
[junit4:junit4] 2> 496 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0006 with negotiated timeout 20000 for client /127.0.0.1:36695
[junit4:junit4] 2> 496 T560 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0006, negotiated timeout = 20000
[junit4:junit4] 2> 496 T561 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1275e3e name:ZooKeeperConnection Watcher:127.0.0.1:36789/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 496 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 497 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0006 type:create cxid:0x1 zxid:0x2d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 497 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0006 type:create cxid:0x2 zxid:0x2e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 499 T522 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 1501 T522 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33124_
[junit4:junit4] 2> 1502 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0006 type:delete cxid:0x9 zxid:0x2f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:33124_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:33124_
[junit4:junit4] 2> 1503 T522 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33124_
[junit4:junit4] 2> 1506 T548 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 1506 T561 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 1507 T544 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 1511 T522 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 1511 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 1512 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 1578 T522 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 1579 T522 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:40210
[junit4:junit4] 2> 1580 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 1580 T522 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 1581 T522 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-1362409677659
[junit4:junit4] 2> 1581 T522 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1362409677659/solr.xml
[junit4:junit4] 2> 1581 T522 oasc.CoreContainer.<init> New CoreContainer 16115336
[junit4:junit4] 2> 1582 T522 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-1362409677659/'
[junit4:junit4] 2> 1582 T522 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-1362409677659/'
[junit4:junit4] 2> 1598 T522 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 1598 T522 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 1598 T522 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 1599 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 1599 T522 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 1599 T522 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 1600 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 1600 T522 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 1600 T522 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 1601 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 1607 T522 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 1615 T522 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36789/solr
[junit4:junit4] 2> 1616 T522 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 1616 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@17ca0a4
[junit4:junit4] 2> 1617 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1617 T571 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 1617 T571 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 1618 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36702
[junit4:junit4] 2> 1618 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36702
[junit4:junit4] 2> 1619 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0007 with negotiated timeout 20000 for client /127.0.0.1:36702
[junit4:junit4] 2> 1619 T571 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0007, negotiated timeout = 20000
[junit4:junit4] 2> 1620 T572 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17ca0a4 name:ZooKeeperConnection Watcher:127.0.0.1:36789 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1620 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1621 T527 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d35f1dd7a0007
[junit4:junit4] 2> 1622 T572 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 1622 T524 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36702 which had sessionid 0x13d35f1dd7a0007
[junit4:junit4] 2> 1622 T522 oaz.ZooKeeper.close Session: 0x13d35f1dd7a0007 closed
[junit4:junit4] 2> 1622 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 1625 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@338f03
[junit4:junit4] 2> 1626 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1627 T573 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 1627 T573 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 1627 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36703
[junit4:junit4] 2> 1628 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36703
[junit4:junit4] 2> 1629 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0008 with negotiated timeout 20000 for client /127.0.0.1:36703
[junit4:junit4] 2> 1629 T573 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0008, negotiated timeout = 20000
[junit4:junit4] 2> 1629 T574 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@338f03 name:ZooKeeperConnection Watcher:127.0.0.1:36789/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1629 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1630 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0008 type:create cxid:0x1 zxid:0x35 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1631 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0008 type:create cxid:0x2 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1633 T522 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 2635 T522 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40210_
[junit4:junit4] 2> 2636 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0008 type:delete cxid:0x9 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:40210_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:40210_
[junit4:junit4] 2> 2637 T522 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:40210_
[junit4:junit4] 2> 2640 T544 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 2640 T561 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 2640 T548 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 2640 T574 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 2653 T522 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 2654 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 2654 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 2718 T522 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 2720 T522 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55563
[junit4:junit4] 2> 2721 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 2721 T522 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 2721 T522 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-1362409678802
[junit4:junit4] 2> 2722 T522 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1362409678802/solr.xml
[junit4:junit4] 2> 2722 T522 oasc.CoreContainer.<init> New CoreContainer 25843118
[junit4:junit4] 2> 2722 T522 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-1362409678802/'
[junit4:junit4] 2> 2723 T522 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-1362409678802/'
[junit4:junit4] 2> 2740 T522 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 2741 T522 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 2741 T522 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 2741 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 2742 T522 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 2742 T522 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 2742 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 2743 T522 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 2743 T522 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 2743 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 2750 T522 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 2760 T522 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36789/solr
[junit4:junit4] 2> 2760 T522 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 2760 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@b6a6c0
[junit4:junit4] 2> 2761 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 2762 T584 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 2762 T584 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 2762 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36704
[junit4:junit4] 2> 2762 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36704
[junit4:junit4] 2> 2763 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a0009 with negotiated timeout 20000 for client /127.0.0.1:36704
[junit4:junit4] 2> 2763 T584 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a0009, negotiated timeout = 20000
[junit4:junit4] 2> 2764 T585 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b6a6c0 name:ZooKeeperConnection Watcher:127.0.0.1:36789 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 2764 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 2765 T527 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d35f1dd7a0009
[junit4:junit4] 2> 2766 T524 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36704 which had sessionid 0x13d35f1dd7a0009
[junit4:junit4] 2> 2765 T522 oaz.ZooKeeper.close Session: 0x13d35f1dd7a0009 closed
[junit4:junit4] 2> 2766 T585 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 2766 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 2769 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@a10df4
[junit4:junit4] 2> 2770 T586 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 2770 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 2770 T586 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 2771 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36705
[junit4:junit4] 2> 2771 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36705
[junit4:junit4] 2> 2772 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a000a with negotiated timeout 20000 for client /127.0.0.1:36705
[junit4:junit4] 2> 2772 T586 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a000a, negotiated timeout = 20000
[junit4:junit4] 2> 2772 T587 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a10df4 name:ZooKeeperConnection Watcher:127.0.0.1:36789/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 2772 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 2773 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a000a type:create cxid:0x1 zxid:0x3d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 2774 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a000a type:create cxid:0x2 zxid:0x3e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 2775 T522 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 3777 T522 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55563_
[junit4:junit4] 2> 3778 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a000a type:delete cxid:0x9 zxid:0x3f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55563_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55563_
[junit4:junit4] 2> 3779 T522 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55563_
[junit4:junit4] 2> 3782 T544 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 3782 T548 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 3782 T561 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 3782 T574 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 3782 T587 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 3786 T522 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 3787 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 3787 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 3846 T522 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 3848 T522 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:39430
[junit4:junit4] 2> 3849 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 3849 T522 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 3849 T522 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-1362409679934
[junit4:junit4] 2> 3850 T522 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1362409679934/solr.xml
[junit4:junit4] 2> 3850 T522 oasc.CoreContainer.<init> New CoreContainer 26570604
[junit4:junit4] 2> 3850 T522 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-1362409679934/'
[junit4:junit4] 2> 3851 T522 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-1362409679934/'
[junit4:junit4] 2> 3866 T522 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 3866 T522 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 3867 T522 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 3867 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 3867 T522 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 3867 T522 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 3868 T522 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 3868 T522 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 3868 T522 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 3869 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 3884 T522 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 3892 T522 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36789/solr
[junit4:junit4] 2> 3893 T522 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 3893 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@14c856d
[junit4:junit4] 2> 3894 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3894 T597 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3895 T597 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 3895 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36706
[junit4:junit4] 2> 3895 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36706
[junit4:junit4] 2> 3896 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a000b with negotiated timeout 20000 for client /127.0.0.1:36706
[junit4:junit4] 2> 3896 T597 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a000b, negotiated timeout = 20000
[junit4:junit4] 2> 3896 T598 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14c856d name:ZooKeeperConnection Watcher:127.0.0.1:36789 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3896 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3897 T527 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d35f1dd7a000b
[junit4:junit4] 2> 3897 T598 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 3897 T524 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36706 which had sessionid 0x13d35f1dd7a000b
[junit4:junit4] 2> 3897 T522 oaz.ZooKeeper.close Session: 0x13d35f1dd7a000b closed
[junit4:junit4] 2> 3898 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 3901 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@155e517
[junit4:junit4] 2> 3902 T599 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3901 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3902 T599 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 3902 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36707
[junit4:junit4] 2> 3902 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36707
[junit4:junit4] 2> 3903 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a000c with negotiated timeout 20000 for client /127.0.0.1:36707
[junit4:junit4] 2> 3903 T599 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a000c, negotiated timeout = 20000
[junit4:junit4] 2> 3903 T600 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@155e517 name:ZooKeeperConnection Watcher:127.0.0.1:36789/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3903 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3904 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a000c type:create cxid:0x1 zxid:0x45 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3905 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a000c type:create cxid:0x2 zxid:0x46 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3906 T522 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 4910 T522 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39430_
[junit4:junit4] 2> 4911 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a000c type:delete cxid:0x9 zxid:0x47 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:39430_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:39430_
[junit4:junit4] 2> 4911 T522 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39430_
[junit4:junit4] 2> 4915 T561 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 4915 T548 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 4915 T574 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 4916 T544 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 4916 T587 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 4916 T600 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 4921 T522 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4] 2> 4922 T522 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 4923 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 4928 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 4942 T556 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-1362409676513/unloadcollection1
[junit4:junit4] 2> 4942 T556 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4] 2> 4943 T556 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:unloadcollection
[junit4:junit4] 2> 4944 T556 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4] 2> 4945 T556 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4] 2> 4945 T556 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection
[junit4:junit4] 2> 4948 T556 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4] 2> 4949 T556 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-1362409676513/unloadcollection1/'
[junit4:junit4] 2> 4977 T556 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4] 2> 5021 T556 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 5022 T556 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 5026 T556 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 5401 T556 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 5407 T556 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 5409 T556 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 5424 T556 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 5427 T556 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 5430 T556 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 5431 T556 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 5433 T556 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 5433 T556 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 5434 T556 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 5434 T556 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 5435 T556 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-1362409676513/unloadcollection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n/
[junit4:junit4] 2> 5435 T556 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@44f7fd
[junit4:junit4] 2> 5435 T556 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 5436 T556 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n forceNew:false
[junit4:junit4] 2> 5436 T556 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n
[junit4:junit4] 2> 5436 T556 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-1362409676150/1362409681080unloadcollection1_1n/index/
[junit4:junit4] 2> 5437 T556 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-1362409676150/1362409681080unloadcollection1_1n/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 5438 T556 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n/index forceNew:false
[junit4:junit4] 2> 5457 T556 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 5457 T556 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 5458 T556 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n/index
[junit4:junit4] 2> 5459 T556 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 5460 T556 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 5460 T556 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 5461 T556 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 5461 T556 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 5461 T556 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 5461 T556 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 5462 T556 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 5462 T556 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 5465 T556 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 5467 T556 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n
[junit4:junit4] 2> 5468 T556 oass.SolrIndexSearcher.<init> Opening Searcher@b94b19 main
[junit4:junit4] 2> 5468 T556 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n/tlog
[junit4:junit4] 2> 5469 T556 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 5469 T556 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 5473 T601 oasc.SolrCore.registerSearcher [unloadcollection1] Registered new searcher Searcher@b94b19 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 5474 T556 oasc.ZkController.publish publishing core=unloadcollection1 state=down
[junit4:junit4] 2> 5475 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0006 type:create cxid:0x3e zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4] 2> 6368 T545 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6369 T545 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "collection":"unloadcollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:33124_",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:33124",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "core":"unloadcollection1"}
[junit4:junit4] 2> 6369 T545 oasc.Overseer$ClusterStateUpdater.createCollection Create collection unloadcollection with numShards 1
[junit4:junit4] 2> 6369 T545 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 6370 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0003 type:create cxid:0x41 zxid:0x4e txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4] 2> 6376 T561 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> 6376 T548 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> 6376 T574 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> 6376 T600 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> 6376 T544 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> 6376 T587 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> 6478 T556 oasc.CoreContainer.registerCore registering core: unloadcollection1
[junit4:junit4] 2> 6478 T556 oasc.ZkController.register Register replica - core:unloadcollection1 address:http://127.0.0.1:33124 collection:unloadcollection shard:shard1
[junit4:junit4] 2> 6479 T556 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leader_elect/shard1/election
[junit4:junit4] 2> 6485 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0006 type:delete cxid:0x4f zxid:0x58 txntype:-1 reqpath:n/a Error Path:/solr/collections/unloadcollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/unloadcollection/leaders
[junit4:junit4] 2> 6485 T556 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 6486 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0006 type:create cxid:0x50 zxid:0x59 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 6487 T556 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 6487 T556 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 6488 T556 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:33124/unloadcollection1/
[junit4:junit4] 2> 6488 T556 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 6488 T556 oasc.SyncStrategy.syncToMe http://127.0.0.1:33124/unloadcollection1/ has no replicas
[junit4:junit4] 2> 6488 T556 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:33124/unloadcollection1/
[junit4:junit4] 2> 6489 T556 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leaders/shard1
[junit4:junit4] 2> 6492 T527 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d35f1dd7a0006 type:create cxid:0x5a zxid:0x5d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 7881 T545 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7894 T600 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> 7894 T587 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> 7895 T548 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> 7894 T561 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> 7894 T574 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> 7895 T544 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> 7947 T556 oasc.ZkController.register We are http://127.0.0.1:33124/unloadcollection1/ and leader is http://127.0.0.1:33124/unloadcollection1/
[junit4:junit4] 2> 7947 T556 oasc.ZkController.register No LogReplay needed for core=unloadcollection1 baseURL=http://127.0.0.1:33124
[junit4:junit4] 2> 7947 T556 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 7948 T556 oasc.ZkController.publish publishing core=unloadcollection1 state=active
[junit4:junit4] 2> 7950 T556 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7951 T556 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-1362409676513/solr.xml
[junit4:junit4] 2> 7962 T556 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-1362409676513/solr.xml
[junit4:junit4] 2> 7964 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 7966 T522 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36789/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6e8869
[junit4:junit4] 2> 7967 T602 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36789. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 7966 T522 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 7967 T602 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36789, initiating session
[junit4:junit4] 2> 7967 T524 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36722
[junit4:junit4] 2> 7967 T524 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36722
[junit4:junit4] 2> 7968 T526 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d35f1dd7a000d with negotiated timeout 10000 for client /127.0.0.1:36722
[junit4:junit4] 2> 7968 T602 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36789, sessionid = 0x13d35f1dd7a000d, negotiated timeout = 10000
[junit4:junit4] 2> 7968 T603 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6e8869 name:ZooKeeperConnection Watcher:127.0.0.1:36789/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 7968 T522 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 7969 T522 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 7971 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7972 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 7977 T569 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-1362409677659/unloadcollection2
[junit4:junit4] 2> 7977 T569 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4] 2> 7978 T569 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 7978 T569 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4] 2> 7979 T569 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-1362409677659/unloadcollection2/'
[junit4:junit4] 2> 8044 T569 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4] 2> 8126 T569 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 8128 T569 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 8133 T569 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 8506 T569 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 8512 T569 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 8514 T569 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 8529 T569 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 8532 T569 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 8535 T569 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 8536 T569 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 8537 T569 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 8537 T569 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 8538 T569 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 8538 T569 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 8539 T569 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-1362409677659/unloadcollection2/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/
[junit4:junit4] 2> 8539 T569 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@44f7fd
[junit4:junit4] 2> 8540 T569 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 8540 T569 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n forceNew:false
[junit4:junit4] 2> 8540 T569 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n
[junit4:junit4] 2> 8541 T569 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-1362409676150/1362409684119unloadcollection1_2n/index/
[junit4:junit4] 2> 8541 T569 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-1362409676150/1362409684119unloadcollection1_2n/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 8542 T569 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/index forceNew:false
[junit4:junit4] 2> 8545 T569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521063tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a7cf8a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 8545 T569 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 8546 T569 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/index
[junit4:junit4] 2> 8547 T569 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 8547 T569 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 8548 T569 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 8548 T569 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 8549 T569 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 8549 T569 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 8549 T569 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 8549 T569 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 8550 T569 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 8552 T569 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 8554 T569 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n
[junit4:junit4] 2> 8555 T569 oass.SolrIndexSearcher.<init> Opening Searcher@1fe738b main
[junit4:junit4] 2> 8555 T569 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/tlog
[junit4:junit4] 2> 8556 T569 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 8556 T569 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 8559 T604 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@1fe738b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 8561 T569 oasc.ZkController.publish publishing core=unloadcollection2 state=down
[junit4:junit4] 2> 8562 T569 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 9401 T545 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9402 T545 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "collection":"unloadcollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:33124_",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:33124",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "core":"unloadcollection1"}
[junit4:junit4] 2> 9406 T545 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "collection":"unloadcollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:40210_",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:40210",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "core":"unloadcollection2"}
[junit4:junit4] 2> 9406 T545 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 9406 T545 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 9410 T603 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> 9411 T600 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> 9410 T548 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> 9411 T587 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> 9411 T574 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> 9411 T561 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> 9411 T544 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> 9564 T569 oasc.CoreContainer.registerCore registering core: unloadcollection2
[junit4:junit4] 2> 9564 T569 oasc.ZkController.register Register replica - core:unloadcollection2 address:http://127.0.0.1:40210 collection:unloadcollection shard:shard1
[junit4:junit4] 2> 9568 T569 oasc.ZkController.register We are http://127.0.0.1:40210/unloadcollection2/ and leader is http://127.0.0.1:33124/unloadcollection1/
[junit4:junit4] 2> 9568 T569 oasc.ZkController.register No LogReplay needed for core=unloadcollection2 baseURL=http://127.0.0.1:40210
[junit4:junit4] 2> 9568 T569 oasc.ZkController.checkRecovery Core needs to recover:unloadcollection2
[junit4:junit4] 2> 9568 T569 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C28 name=unloadcollection2 org.apache.solr.core.SolrCore@c3f23e url=http://127.0.0.1:40210/unloadcollection2 node=127.0.0.1:40210_ C28_STATE=coll:unloadcollection core:unloadcollection2 props:{collection=unloadcollection, node_name=127.0.0.1:40210_, state=down, base_url=http://127.0.0.1:40210, core=unloadcollection2}
[junit4:junit4] 2> 9572 T605 C28 P40210 oasc.RecoveryStrategy.run Starting recovery process. core=unloadcollection2 recoveringAfterStartup=true
[junit4:junit4] 2> 9573 T605 C28 P40210 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 9572 T569 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9573 T605 C28 P40210 oasc.ZkController.publish publishing core=unloadcollection2 state=recovering
[junit4:junit4] 2> 9573 T605 C28 P40210 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 9573 T569 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-1362409677659/solr.xml
[junit4:junit4] 2> 9574 T605 C28 P40210 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 9582 T569 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-1362409677659/solr.xml
[junit4:junit4] 2> 9583 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9584 T522 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: unloadcollection failOnTimeout:true timeout (sec):230
[junit4:junit4] 2> 9585 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 10587 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 10918 T545 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 10919 T545 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "collection":"unloadcollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:40210_",
[junit4:junit4] 2> "state":"recovering",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:40210",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "core":"unloadcollection2"}
[junit4:junit4] 2> 10936 T603 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> 10936 T548 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> 10936 T574 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> 10936 T544 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> 10936 T600 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> 10936 T587 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> 10936 T561 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> 11589 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 12591 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> C28_STATE=coll:unloadcollection core:unloadcollection2 props:{collection=unloadcollection, node_name=127.0.0.1:40210_, state=recovering, shard=shard1, base_url=http://127.0.0.1:40210, core=unloadcollection2}
[junit4:junit4] 2> 13583 T605 C28 P40210 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:33124/unloadcollection1/ core=unloadcollection2 - recoveringAfterStartup=true
[junit4:junit4] 2> 13586 T605 C28 P40210 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4] 2> 13591 T605 C28 P40210 oasu.PeerSync.sync PeerSync: core=unloadcollection2 url=http://127.0.0.1:40210 START replicas=[http://127.0.0.1:33124/unloadcollection1/] nUpdates=100
[junit4:junit4] 2> 13592 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 13594 T605 C28 P40210 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4] 2> 13594 T605 C28 P40210 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=unloadcollection2
[junit4:junit4] 2> 13594 T605 C28 P40210 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=unloadcollection2
[junit4:junit4] 2> 13594 T605 C28 P40210 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=unloadcollection2
[junit4:junit4] 2> 13594 T605 C28 P40210 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4] 2> 13594 T605 C28 P40210 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:33124/unloadcollection1/. core=unloadcollection2
[junit4:junit4] 2> 13595 T605 C28 P40210 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C29 name=unloadcollection1 org.apache.solr.core.SolrCore@69856f url=http://127.0.0.1:33124/unloadcollection1 node=127.0.0.1:33124_ C29_STATE=coll:unloadcollection core:unloadcollection1 props:{collection=unloadcollection, node_name=127.0.0.1:33124_, state=active, shard=shard1, base_url=http://127.0.0.1:33124, core=unloadcollection1, leader=true}
[junit4:junit4] 2> 13608 T557 C29 P33124 REQ /get {version=2&wt=javabin&distrib=false&getVersions=100&qt=/get} status=0 QTime=0
[junit4:junit4] 2> 13614 T556 C29 P33124 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 13614 T556 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n
[junit4:junit4] 2> 13616 T556 C29 P33124 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 13616 T556 C29 P33124 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 13625 T556 C29 P33124 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 13626 T556 C29 P33124 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4] 2> 13626 T556 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n
[junit4:junit4] 2> 13627 T556 C29 P33124 oass.SolrIndexSearcher.<init> Opening Searcher@ebc511 realtime
[junit4:junit4] 2> 13627 T556 C29 P33124 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 13627 T556 C29 P33124 /update {version=2&wt=javabin&commit_end_point=true&openSearcher=false&waitSearcher=true&commit=true&softCommit=false} {commit=} 0 17
[junit4:junit4] 2> 13628 T605 C28 P40210 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 13629 T605 C28 P40210 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4] 2> 13634 T605 C28 P40210 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 13636 T557 C29 P33124 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 13636 T557 C29 P33124 REQ /replication {version=2&command=indexversion&wt=javabin&qt=/replication} status=0 QTime=1
[junit4:junit4] 2> 13637 T605 C28 P40210 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4] 2> 13637 T605 C28 P40210 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 13637 T605 C28 P40210 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 13639 T556 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n
[junit4:junit4] 2> 13640 T556 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n/index
[junit4:junit4] 2> 13640 T556 C29 P33124 REQ /replication {version=2&command=filelist&wt=javabin&generation=2&qt=/replication} status=0 QTime=1
[junit4:junit4] 2> 13641 T605 C28 P40210 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4] 2> 13642 T605 C28 P40210 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/index.20130305020809786 forceNew:false
[junit4:junit4] 2> 13642 T605 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n
[junit4:junit4] 2> 13643 T605 C28 P40210 oash.SnapPuller.fetchLatestIndex Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4d7b5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@95942e) fullCopy=false
[junit4:junit4] 2> 13648 T555 C29 P33124 REQ /replication {file=segments_2&command=filecontent&wt=filestream&generation=2&qt=/replication&checksum=true} status=0 QTime=2
[junit4:junit4] 2> 13650 T605 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/index
[junit4:junit4] 2> 13650 T605 C28 P40210 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 13651 T605 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n
[junit4:junit4] 2> 13652 T605 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n
[junit4:junit4] 2> 13652 T605 C28 P40210 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 13653 T605 C28 P40210 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=unloadcollection2
[junit4:junit4] 2> 13653 T605 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n
[junit4:junit4] 2> 13655 T605 C28 P40210 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521063tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a7cf8a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521063tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a7cf8a),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 13655 T605 C28 P40210 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4] 2> 13656 T605 C28 P40210 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 13656 T605 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n
[junit4:junit4] 2> 13656 T605 C28 P40210 oass.SolrIndexSearcher.<init> Opening Searcher@1f7d5d2 main
[junit4:junit4] 2> 13657 T604 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@1f7d5d2 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4] 2> 13658 T604 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/index
[junit4:junit4] 2> 13658 T605 C28 P40210 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4d7b5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@95942e)
[junit4:junit4] 2> 13658 T605 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/index.20130305020809786
[junit4:junit4] 2> 13659 T605 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/index
[junit4:junit4] 2> 13659 T605 C28 P40210 oasc.RecoveryStrategy.replay No replay needed. core=unloadcollection2
[junit4:junit4] 2> 13659 T605 C28 P40210 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=unloadcollection2
[junit4:junit4] 2> 13659 T605 C28 P40210 oasc.ZkController.publish publishing core=unloadcollection2 state=active
[junit4:junit4] 2> 13660 T605 C28 P40210 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 13662 T605 C28 P40210 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=unloadcollection2
[junit4:junit4] 2> 13945 T545 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 13946 T545 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "collection":"unloadcollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:40210_",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:40210",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "core":"unloadcollection2"}
[junit4:junit4] 2> 13950 T548 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> 13950 T603 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> 13951 T574 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> 13951 T561 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> 13950 T600 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> 13950 T587 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> 13950 T544 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> 14594 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 14595 T522 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: unloadcollection
[junit4:junit4] 2> 14595 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> C28_STATE=coll:unloadcollection core:unloadcollection2 props:{collection=unloadcollection, node_name=127.0.0.1:40210_, state=active, shard=shard1, base_url=http://127.0.0.1:40210, core=unloadcollection2}
[junit4:junit4] 2> 14633 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[6 (1428590103885774848)]} 0 3
[junit4:junit4] 2> 14634 T555 C29 P33124 /update {version=2&wt=javabin} {add=[6 (1428590103885774848)]} 0 33
[junit4:junit4] 2> 14645 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[7 (1428590103920377856)]} 0 3
[junit4:junit4] 2> 14646 T554 C29 P33124 /update {version=2&wt=javabin} {add=[7 (1428590103920377856)]} 0 9
[junit4:junit4] 2> 14653 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[8 (1428590103932960768)]} 0 1
[junit4:junit4] 2> 14654 T557 C29 P33124 /update {version=2&wt=javabin} {add=[8 (1428590103932960768)]} 0 5
[junit4:junit4] 2> 14657 T556 C29 P33124 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 14675 T556 C29 P33124 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_3,generation=3,filenames=[_0_Lucene40_0.prx, segments_3, _0_Lucene40_0.frq, _0_Lucene40_0.tip, _0.si, _0.fnm, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> 14676 T556 C29 P33124 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0_Lucene40_0.prx, segments_3, _0_Lucene40_0.frq, _0_Lucene40_0.tip, _0.si, _0.fnm, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> 14677 T556 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n
[junit4:junit4] 2> 14680 T556 C29 P33124 oass.SolrIndexSearcher.<init> Opening Searcher@8b322a main
[junit4:junit4] 2> 14680 T556 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n/index
[junit4:junit4] 2> 14681 T556 C29 P33124 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 14682 T601 oasc.SolrCore.registerSearcher [unloadcollection1] Registered new searcher Searcher@8b322a main{StandardDirectoryReader(segments_3:3:nrt _0(4.2):C3)}
[junit4:junit4] 2> 14682 T601 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n/index
[junit4:junit4] 2> 14683 T556 C29 P33124 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:40210/unloadcollection2/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4] 2> 14686 T567 C28 P40210 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 14705 T567 C28 P40210 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521063tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a7cf8a),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521063tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a7cf8a),segFN=segments_3,generation=3,filenames=[_0_Lucene40_0.prx, segments_3, _0_Lucene40_0.frq, _0_Lucene40_0.tip, _0.si, _0.fnm, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> 14706 T567 C28 P40210 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0_Lucene40_0.prx, segments_3, _0_Lucene40_0.frq, _0_Lucene40_0.tip, _0.si, _0.fnm, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> 14706 T567 C28 P40210 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n
[junit4:junit4] 2> 14709 T567 C28 P40210 oass.SolrIndexSearcher.<init> Opening Searcher@82d881 main
[junit4:junit4] 2> 14710 T567 C28 P40210 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 14710 T604 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@82d881 main{StandardDirectoryReader(segments_3:3:nrt _0(4.2):C3)}
[junit4:junit4] 2> 14711 T604 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409684119unloadcollection1_2n/index
[junit4:junit4] 2> 14711 T567 C28 P40210 /update {version=2&wt=javabin&commit_end_point=true&expungeDeletes=false&waitSearcher=true&commit=true&softCommit=false} {commit=} 0 25
[junit4:junit4] 2> 14711 T556 C29 P33124 /update {version=2&wt=javabin&waitSearcher=true&commit=true&softCommit=false} {commit=} 0 54
[junit4:junit4] 2> 14712 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 14718 T581 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-1362409678802/unloadcollection3
[junit4:junit4] 2> 14719 T581 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4] 2> 14720 T581 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 14720 T581 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4] 2> 14721 T581 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-1362409678802/unloadcollection3/'
[junit4:junit4] 2> 14767 T581 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4] 2> 14812 T581 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 14813 T581 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 14826 T581 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 15264 T581 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 15270 T581 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 15272 T581 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 15287 T581 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 15290 T581 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 15293 T581 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 15295 T581 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 15295 T581 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 15295 T581 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 15296 T581 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 15297 T581 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 15297 T581 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-1362409678802/unloadcollection3/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/
[junit4:junit4] 2> 15297 T581 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@44f7fd
[junit4:junit4] 2> 15297 T581 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 15298 T581 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n forceNew:false
[junit4:junit4] 2> 15298 T581 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n
[junit4:junit4] 2> 15299 T581 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-1362409676150/1362409690860unloadcollection_3n/index/
[junit4:junit4] 2> 15299 T581 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-1362409676150/1362409690860unloadcollection_3n/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 15300 T581 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/index forceNew:false
[junit4:junit4] 2> 15302 T581 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521064tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e9387c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 15303 T581 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 15303 T581 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/index
[junit4:junit4] 2> 15305 T581 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 15305 T581 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 15305 T581 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 15306 T581 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 15306 T581 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 15306 T581 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 15307 T581 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 15307 T581 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 15307 T581 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 15310 T581 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 15312 T581 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n
[junit4:junit4] 2> 15313 T581 oass.SolrIndexSearcher.<init> Opening Searcher@5f5d8e main
[junit4:junit4] 2> 15313 T581 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/tlog
[junit4:junit4] 2> 15314 T581 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 15314 T581 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 15317 T609 oasc.SolrCore.registerSearcher [unloadcollection3] Registered new searcher Searcher@5f5d8e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 15318 T581 oasc.ZkController.publish publishing core=unloadcollection3 state=down
[junit4:junit4] 2> 15318 T581 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 15457 T545 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 15457 T545 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "collection":"unloadcollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:55563_",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:55563",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "core":"unloadcollection3"}
[junit4:junit4] 2> 15458 T545 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 15458 T545 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 15461 T603 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> 15461 T548 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> 15461 T600 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> 15461 T587 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> 15462 T561 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> 15462 T574 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> 15462 T544 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> 16319 T581 oasc.CoreContainer.registerCore registering core: unloadcollection3
[junit4:junit4] 2> 16320 T581 oasc.ZkController.register Register replica - core:unloadcollection3 address:http://127.0.0.1:55563 collection:unloadcollection shard:shard1
[junit4:junit4] 2> 16324 T581 oasc.ZkController.register We are http://127.0.0.1:55563/unloadcollection3/ and leader is http://127.0.0.1:33124/unloadcollection1/
[junit4:junit4] 2> 16324 T581 oasc.ZkController.register No LogReplay needed for core=unloadcollection3 baseURL=http://127.0.0.1:55563
[junit4:junit4] 2> 16324 T581 oasc.ZkController.checkRecovery Core needs to recover:unloadcollection3
[junit4:junit4] 2> 16324 T581 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C30 name=unloadcollection3 org.apache.solr.core.SolrCore@561ba9 url=http://127.0.0.1:55563/unloadcollection3 node=127.0.0.1:55563_ C30_STATE=coll:unloadcollection core:unloadcollection3 props:{collection=unloadcollection, node_name=127.0.0.1:55563_, state=down, base_url=http://127.0.0.1:55563, core=unloadcollection3}
[junit4:junit4] 2> 16325 T610 C30 P55563 oasc.RecoveryStrategy.run Starting recovery process. core=unloadcollection3 recoveringAfterStartup=true
[junit4:junit4] 2> 16326 T610 C30 P55563 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 16326 T610 C30 P55563 oasc.ZkController.publish publishing core=unloadcollection3 state=recovering
[junit4:junit4] 2> 16326 T610 C30 P55563 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 16326 T581 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 16327 T610 C30 P55563 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 16327 T581 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-1362409678802/solr.xml
[junit4:junit4] 2> 16336 T581 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-1362409678802/solr.xml
[junit4:junit4] 2> 16337 T522 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: unloadcollection failOnTimeout:true timeout (sec):230
[junit4:junit4] 2> 16338 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 16967 T545 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 16968 T545 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "collection":"unloadcollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:55563_",
[junit4:junit4] 2> "state":"recovering",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:55563",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "core":"unloadcollection3"}
[junit4:junit4] 2> 16971 T600 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> 16972 T603 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> 16972 T574 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> 16972 T544 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> 16972 T561 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> 16972 T548 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> 16972 T587 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> 17340 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 18342 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> C30_STATE=coll:unloadcollection core:unloadcollection3 props:{collection=unloadcollection, node_name=127.0.0.1:55563_, state=recovering, shard=shard1, base_url=http://127.0.0.1:55563, core=unloadcollection3}
[junit4:junit4] 2> 19335 T610 C30 P55563 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:33124/unloadcollection1/ core=unloadcollection3 - recoveringAfterStartup=true
[junit4:junit4] 2> 19335 T610 C30 P55563 oasu.PeerSync.sync PeerSync: core=unloadcollection3 url=http://127.0.0.1:55563 START replicas=[http://127.0.0.1:33124/unloadcollection1/] nUpdates=100
[junit4:junit4] 2> 19336 T610 C30 P55563 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4] 2> 19336 T610 C30 P55563 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=unloadcollection3
[junit4:junit4] 2> 19337 T610 C30 P55563 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=unloadcollection3
[junit4:junit4] 2> 19337 T610 C30 P55563 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=unloadcollection3
[junit4:junit4] 2> 19337 T610 C30 P55563 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4] 2> 19338 T610 C30 P55563 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:33124/unloadcollection1/. core=unloadcollection3
[junit4:junit4] 2> 19338 T610 C30 P55563 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 19344 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 19347 T556 C29 P33124 REQ /get {version=2&wt=javabin&distrib=false&getVersions=100&qt=/get} status=0 QTime=7
[junit4:junit4] 2> 19348 T557 C29 P33124 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 19356 T557 C29 P33124 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=3
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_3,generation=3,filenames=[_0_Lucene40_0.prx, segments_3, _0_Lucene40_0.frq, _0_Lucene40_0.tip, _0.si, _0.fnm, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521062tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@103c100),segFN=segments_4,generation=4,filenames=[_0_Lucene40_0.prx, _0_Lucene40_0.frq, _0_Lucene40_0.tip, segments_4, _0.si, _0.fnm, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> 19357 T557 C29 P33124 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[_0_Lucene40_0.prx, _0_Lucene40_0.frq, _0_Lucene40_0.tip, segments_4, _0.si, _0.fnm, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> 19358 T557 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n
[junit4:junit4] 2> 19358 T557 C29 P33124 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 19358 T557 C29 P33124 /update {version=2&wt=javabin&commit_end_point=true&openSearcher=false&waitSearcher=true&commit=true&softCommit=false} {commit=} 0 10
[junit4:junit4] 2> 19359 T610 C30 P55563 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 19359 T610 C30 P55563 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 19362 T556 C29 P33124 REQ /replication {version=2&command=indexversion&wt=javabin&qt=/replication} status=0 QTime=0
[junit4:junit4] 2> 19363 T610 C30 P55563 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4:junit4] 2> 19363 T610 C30 P55563 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 19363 T610 C30 P55563 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 19365 T554 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n
[junit4:junit4] 2> 19366 T554 C29 P33124 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409681080unloadcollection1_1n/index
[junit4:junit4] 2> 19366 T554 C29 P33124 REQ /replication {version=2&command=filelist&wt=javabin&generation=4&qt=/replication} status=0 QTime=1
[junit4:junit4] 2> 19367 T610 C30 P55563 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 11
[junit4:junit4] 2> 19367 T610 C30 P55563 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/index.20130305020815511 forceNew:false
[junit4:junit4] 2> 19367 T610 C30 P55563 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n
[junit4:junit4] 2> 19368 T610 C30 P55563 oash.SnapPuller.fetchLatestIndex Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@96847f lockFactory=org.apache.lucene.store.NativeFSLockFactory@6031e9) fullCopy=false
[junit4:junit4] 2> 19381 T557 C29 P33124 REQ /replication {file=_0_Lucene40_0.prx&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19387 T555 C29 P33124 REQ /replication {file=_0_Lucene40_0.frq&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19393 T556 C29 P33124 REQ /replication {file=segments_4&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 19398 T554 C29 P33124 REQ /replication {file=_0_Lucene40_0.tip&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19403 T557 C29 P33124 REQ /replication {file=_0.fnm&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 19408 T555 C29 P33124 REQ /replication {file=_0.si&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19411 T556 C29 P33124 REQ /replication {file=_0_Lucene40_0.tim&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19416 T554 C29 P33124 REQ /replication {file=_0.fdx&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19470 T557 C29 P33124 REQ /replication {file=_0_nrm.cfs&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19474 T555 C29 P33124 REQ /replication {file=_0.fdt&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19478 T556 C29 P33124 REQ /replication {file=_0_nrm.cfe&command=filecontent&wt=filestream&generation=4&qt=/replication&checksum=true} status=0 QTime=0
[junit4:junit4] 2> 19479 T610 C30 P55563 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/index
[junit4:junit4] 2> 19479 T610 C30 P55563 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 19481 T610 C30 P55563 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n
[junit4:junit4] 2> 19482 T610 C30 P55563 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n
[junit4:junit4] 2> 19482 T610 C30 P55563 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 19482 T610 C30 P55563 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=unloadcollection3
[junit4:junit4] 2> 19482 T610 C30 P55563 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n
[junit4:junit4] 2> 19485 T610 C30 P55563 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521064tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e9387c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/index2105521064tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e9387c),segFN=segments_4,generation=4,filenames=[_0_Lucene40_0.prx, _0_Lucene40_0.frq, _0_Lucene40_0.tip, segments_4, _0.fnm, _0.si, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> 19486 T610 C30 P55563 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[_0_Lucene40_0.prx, _0_Lucene40_0.frq, _0_Lucene40_0.tip, segments_4, _0.fnm, _0.si, _0_Lucene40_0.tim, _0.fdx, _0_nrm.cfs, _0.fdt, _0_nrm.cfe]
[junit4:junit4] 2> 19486 T610 C30 P55563 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 19486 T610 C30 P55563 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n
[junit4:junit4] 2> 19488 T610 C30 P55563 oass.SolrIndexSearcher.<init> Opening Searcher@1272c04 main
[junit4:junit4] 2> 19489 T609 oasc.SolrCore.registerSearcher [unloadcollection3] Registered new searcher Searcher@1272c04 main{StandardDirectoryReader(segments_4:3:nrt _0(4.2):C3)}
[junit4:junit4] 2> 19490 T609 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/index
[junit4:junit4] 2> 19490 T610 C30 P55563 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@96847f lockFactory=org.apache.lucene.store.NativeFSLockFactory@6031e9)
[junit4:junit4] 2> 19490 T610 C30 P55563 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/index.20130305020815511
[junit4:junit4] 2> 19490 T610 C30 P55563 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362409676150/1362409690860unloadcollection_3n/index
[junit4:junit4] 2> 19490 T610 C30 P55563 oasc.RecoveryStrategy.replay No replay needed. core=unloadcollection3
[junit4:junit4] 2> 19490 T610 C30 P55563 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=unloadcollection3
[junit4:junit4] 2> 19491 T610 C30 P55563 oasc.ZkController.publish publishing core=unloadcollection3 state=active
[junit4:junit4] 2> 19491 T610 C30 P55563 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 19493 T610 C30 P55563 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=unloadcollection3
[junit4:junit4] 2> 19980 T545 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 19981 T545 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "collection":"unloadcollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:55563_",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:55563",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "core":"unloadcollection3"}
[junit4:junit4] 2> 20006 T574 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> 20006 T603 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> 20006 T587 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> 20006 T600 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> 20006 T561 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> 20006 T548 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> 20006 T544 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> 20347 T522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 20349 T522 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: unloadcollection
[junit4:junit4] 2> 20349 T522 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> C30_STATE=coll:unloadcollection core:unloadcollection3 props:{collection=unloadcollection, node_name=127.0.0.1:55563_, state=active, shard=shard1, base_url=http://127.0.0.1:55563, core=unloadcollection3}
[junit4:junit4] 2> 20397 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[20 (1428590109946544128)]} 0 5
[junit4:junit4] 2> 20398 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[20 (1428590109946544128)]} 0 6
[junit4:junit4] 2> 20400 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[20 (1428590109946544128)]} 0 17
[junit4:junit4] 2> 20401 T582 C30 P55563 /update {version=2&wt=javabin} {add=[20]} 0 24
[junit4:junit4] 2> 20419 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[21 (1428590109972758528)]} 0 4
[junit4:junit4] 2> 20420 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[21 (1428590109972758528)]} 0 5
[junit4:junit4] 2> 20422 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[21 (1428590109972758528)]} 0 14
[junit4:junit4] 2> 20423 T581 C30 P55563 /update {version=2&wt=javabin} {add=[21]} 0 19
[junit4:junit4] 2> 20435 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[22 (1428590109993730048)]} 0 3
[junit4:junit4] 2> 20435 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[22 (1428590109993730048)]} 0 3
[junit4:junit4] 2> 20436 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[22 (1428590109993730048)]} 0 7
[junit4:junit4] 2> 20437 T583 C30 P55563 /update {version=2&wt=javabin} {add=[22]} 0 11
[junit4:junit4] 2> 20453 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[23 (1428590110010507264)]} 0 3
[junit4:junit4] 2> 20454 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[23 (1428590110010507264)]} 0 3
[junit4:junit4] 2> 20455 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[23 (1428590110010507264)]} 0 11
[junit4:junit4] 2> 20456 T580 C30 P55563 /update {version=2&wt=javabin} {add=[23]} 0 16
[junit4:junit4] 2> 20472 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[24 (1428590110030430208)]} 0 3
[junit4:junit4] 2> 20472 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[24 (1428590110030430208)]} 0 3
[junit4:junit4] 2> 20474 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[24 (1428590110030430208)]} 0 10
[junit4:junit4] 2> 20475 T582 C30 P55563 /update {version=2&wt=javabin} {add=[24]} 0 15
[junit4:junit4] 2> 20492 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[25 (1428590110049304576)]} 0 4
[junit4:junit4] 2> 20492 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[25 (1428590110049304576)]} 0 4
[junit4:junit4] 2> 20493 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[25 (1428590110049304576)]} 0 11
[junit4:junit4] 2> 20495 T581 C30 P55563 /update {version=2&wt=javabin} {add=[25]} 0 17
[junit4:junit4] 2> 20511 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[26 (1428590110070276096)]} 0 3
[junit4:junit4] 2> 20512 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[26 (1428590110070276096)]} 0 4
[junit4:junit4] 2> 20513 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[26 (1428590110070276096)]} 0 11
[junit4:junit4] 2> 20515 T583 C30 P55563 /update {version=2&wt=javabin} {add=[26]} 0 17
[junit4:junit4] 2> 20529 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[27 (1428590110092296192)]} 0 2
[junit4:junit4] 2> 20529 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[27 (1428590110092296192)]} 0 1
[junit4:junit4] 2> 20530 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[27 (1428590110092296192)]} 0 8
[junit4:junit4] 2> 20531 T580 C30 P55563 /update {version=2&wt=javabin} {add=[27]} 0 13
[junit4:junit4] 2> 20542 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[28 (1428590110105927680)]} 0 1
[junit4:junit4] 2> 20543 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[28 (1428590110105927680)]} 0 2
[junit4:junit4] 2> 20544 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[28 (1428590110105927680)]} 0 8
[junit4:junit4] 2> 20544 T582 C30 P55563 /update {version=2&wt=javabin} {add=[28]} 0 11
[junit4:junit4] 2> 20556 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[29 (1428590110120607744)]} 0 2
[junit4:junit4] 2> 20556 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[29 (1428590110120607744)]} 0 2
[junit4:junit4] 2> 20556 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[29 (1428590110120607744)]} 0 7
[junit4:junit4] 2> 20557 T581 C30 P55563 /update {version=2&wt=javabin} {add=[29]} 0 10
[junit4:junit4] 2> 20568 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[30 (1428590110133190656)]} 0 2
[junit4:junit4] 2> 20569 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[30 (1428590110133190656)]} 0 3
[junit4:junit4] 2> 20570 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[30 (1428590110133190656)]} 0 8
[junit4:junit4] 2> 20571 T583 C30 P55563 /update {version=2&wt=javabin} {add=[30]} 0 12
[junit4:junit4] 2> 20584 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[31 (1428590110148919296)]} 0 2
[junit4:junit4] 2> 20585 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[31 (1428590110148919296)]} 0 3
[junit4:junit4] 2> 20587 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[31 (1428590110148919296)]} 0 10
[junit4:junit4] 2> 20587 T580 C30 P55563 /update {version=2&wt=javabin} {add=[31]} 0 13
[junit4:junit4] 2> 20597 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[32 (1428590110165696512)]} 0 1
[junit4:junit4] 2> 20598 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[32 (1428590110165696512)]} 0 2
[junit4:junit4] 2> 20599 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[32 (1428590110165696512)]} 0 7
[junit4:junit4] 2> 20599 T582 C30 P55563 /update {version=2&wt=javabin} {add=[32]} 0 9
[junit4:junit4] 2> 20609 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[33 (1428590110177230848)]} 0 2
[junit4:junit4] 2> 20609 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[33 (1428590110177230848)]} 0 2
[junit4:junit4] 2> 20609 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[33 (1428590110177230848)]} 0 5
[junit4:junit4] 2> 20610 T581 C30 P55563 /update {version=2&wt=javabin} {add=[33]} 0 9
[junit4:junit4] 2> 20620 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[34 (1428590110188765184)]} 0 2
[junit4:junit4] 2> 20620 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[34 (1428590110188765184)]} 0 2
[junit4:junit4] 2> 20620 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[34 (1428590110188765184)]} 0 5
[junit4:junit4] 2> 20621 T583 C30 P55563 /update {version=2&wt=javabin} {add=[34]} 0 9
[junit4:junit4] 2> 20634 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[35 (1428590110200299520)]} 0 3
[junit4:junit4] 2> 20634 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[35 (1428590110200299520)]} 0 3
[junit4:junit4] 2> 20635 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[35 (1428590110200299520)]} 0 9
[junit4:junit4] 2> 20636 T580 C30 P55563 /update {version=2&wt=javabin} {add=[35]} 0 13
[junit4:junit4] 2> 20649 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[36 (1428590110217076736)]} 0 2
[junit4:junit4] 2> 20650 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[36 (1428590110217076736)]} 0 3
[junit4:junit4] 2> 20651 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[36 (1428590110217076736)]} 0 10
[junit4:junit4] 2> 20652 T582 C30 P55563 /update {version=2&wt=javabin} {add=[36]} 0 14
[junit4:junit4] 2> 20665 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[37 (1428590110233853952)]} 0 2
[junit4:junit4] 2> 20665 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[37 (1428590110233853952)]} 0 2
[junit4:junit4] 2> 20666 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[37 (1428590110233853952)]} 0 8
[junit4:junit4] 2> 20667 T581 C30 P55563 /update {version=2&wt=javabin} {add=[37]} 0 12
[junit4:junit4] 2> 20676 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[38 (1428590110248534016)]} 0 1
[junit4:junit4] 2> 20676 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[38 (1428590110248534016)]} 0 1
[junit4:junit4] 2> 20677 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[38 (1428590110248534016)]} 0 6
[junit4:junit4] 2> 20677 T583 C30 P55563 /update {version=2&wt=javabin} {add=[38]} 0 8
[junit4:junit4] 2> 20686 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[39 (1428590110259019776)]} 0 1
[junit4:junit4] 2> 20686 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[39 (1428590110259019776)]} 0 1
[junit4:junit4] 2> 20687 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[39 (1428590110259019776)]} 0 6
[junit4:junit4] 2> 20687 T580 C30 P55563 /update {version=2&wt=javabin} {add=[39]} 0 8
[junit4:junit4] 2> 20697 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[40 (1428590110269505536)]} 0 2
[junit4:junit4] 2> 20697 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[40 (1428590110269505536)]} 0 2
[junit4:junit4] 2> 20698 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[40 (1428590110269505536)]} 0 6
[junit4:junit4] 2> 20698 T582 C30 P55563 /update {version=2&wt=javabin} {add=[40]} 0 9
[junit4:junit4] 2> 20708 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[41 (1428590110282088448)]} 0 1
[junit4:junit4] 2> 20708 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[41 (1428590110282088448)]} 0 1
[junit4:junit4] 2> 20709 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[41 (1428590110282088448)]} 0 6
[junit4:junit4] 2> 20710 T581 C30 P55563 /update {version=2&wt=javabin} {add=[41]} 0 9
[junit4:junit4] 2> 20719 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[42 (1428590110293622784)]} 0 1
[junit4:junit4] 2> 20720 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[42 (1428590110293622784)]} 0 2
[junit4:junit4] 2> 20721 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[42 (1428590110293622784)]} 0 7
[junit4:junit4] 2> 20721 T583 C30 P55563 /update {version=2&wt=javabin} {add=[42]} 0 9
[junit4:junit4] 2> 20731 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[43 (1428590110305157120)]} 0 2
[junit4:junit4] 2> 20731 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[43 (1428590110305157120)]} 0 2
[junit4:junit4] 2> 20731 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[43 (1428590110305157120)]} 0 5
[junit4:junit4] 2> 20732 T580 C30 P55563 /update {version=2&wt=javabin} {add=[43]} 0 8
[junit4:junit4] 2> 20742 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[44 (1428590110316691456)]} 0 2
[junit4:junit4] 2> 20742 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[44 (1428590110316691456)]} 0 2
[junit4:junit4] 2> 20743 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[44 (1428590110316691456)]} 0 6
[junit4:junit4] 2> 20743 T582 C30 P55563 /update {version=2&wt=javabin} {add=[44]} 0 9
[junit4:junit4] 2> 20757 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[45 (1428590110330322944)]} 0 3
[junit4:junit4] 2> 20758 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[45 (1428590110330322944)]} 0 4
[junit4:junit4] 2> 20759 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[45 (1428590110330322944)]} 0 10
[junit4:junit4] 2> 20760 T583 C30 P55563 /update {version=2&wt=javabin} {add=[45]} 0 14
[junit4:junit4] 2> 20776 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[46 (1428590110349197312)]} 0 3
[junit4:junit4] 2> 20776 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[46 (1428590110349197312)]} 0 3
[junit4:junit4] 2> 20777 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[46 (1428590110349197312)]} 0 10
[junit4:junit4] 2> 20778 T582 C30 P55563 /update {version=2&wt=javabin} {add=[46]} 0 14
[junit4:junit4] 2> 20792 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[47 (1428590110367023104)]} 0 2
[junit4:junit4] 2> 20792 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[47 (1428590110367023104)]} 0 2
[junit4:junit4] 2> 20793 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[47 (1428590110367023104)]} 0 8
[junit4:junit4] 2> 20793 T581 C30 P55563 /update {version=2&wt=javabin} {add=[47]} 0 11
[junit4:junit4] 2> 20807 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[48 (1428590110382751744)]} 0 2
[junit4:junit4] 2> 20807 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[48 (1428590110382751744)]} 0 2
[junit4:junit4] 2> 20808 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[48 (1428590110382751744)]} 0 9
[junit4:junit4] 2> 20808 T580 C30 P55563 /update {version=2&wt=javabin} {add=[48]} 0 12
[junit4:junit4] 2> 20817 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[49 (1428590110396383232)]} 0 1
[junit4:junit4] 2> 20818 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[49 (1428590110396383232)]} 0 2
[junit4:junit4] 2> 20819 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[49 (1428590110396383232)]} 0 6
[junit4:junit4] 2> 20819 T583 C30 P55563 /update {version=2&wt=javabin} {add=[49]} 0 8
[junit4:junit4] 2> 20829 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[50 (1428590110407917568)]} 0 2
[junit4:junit4] 2> 20829 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[50 (1428590110407917568)]} 0 2
[junit4:junit4] 2> 20830 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[50 (1428590110407917568)]} 0 6
[junit4:junit4] 2> 20831 T582 C30 P55563 /update {version=2&wt=javabin} {add=[50]} 0 10
[junit4:junit4] 2> 20840 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[51 (1428590110419451904)]} 0 2
[junit4:junit4] 2> 20840 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[51 (1428590110419451904)]} 0 2
[junit4:junit4] 2> 20840 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[51 (1428590110419451904)]} 0 5
[junit4:junit4] 2> 20841 T581 C30 P55563 /update {version=2&wt=javabin} {add=[51]} 0 8
[junit4:junit4] 2> 20850 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[52 (1428590110429937664)]} 0 2
[junit4:junit4] 2> 20850 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[52 (1428590110429937664)]} 0 2
[junit4:junit4] 2> 20850 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[52 (1428590110429937664)]} 0 5
[junit4:junit4] 2> 20851 T580 C30 P55563 /update {version=2&wt=javabin} {add=[52]} 0 8
[junit4:junit4] 2> 20860 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[53 (1428590110440423424)]} 0 2
[junit4:junit4] 2> 20860 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[53 (1428590110440423424)]} 0 2
[junit4:junit4] 2> 20861 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[53 (1428590110440423424)]} 0 6
[junit4:junit4] 2> 20861 T583 C30 P55563 /update {version=2&wt=javabin} {add=[53]} 0 8
[junit4:junit4] 2> 20870 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[54 (1428590110451957760)]} 0 1
[junit4:junit4] 2> 20870 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[54 (1428590110451957760)]} 0 1
[junit4:junit4] 2> 20871 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[54 (1428590110451957760)]} 0 5
[junit4:junit4] 2> 20872 T582 C30 P55563 /update {version=2&wt=javabin} {add=[54]} 0 8
[junit4:junit4] 2> 20881 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[55 (1428590110462443520)]} 0 2
[junit4:junit4] 2> 20881 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[55 (1428590110462443520)]} 0 2
[junit4:junit4] 2> 20881 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[55 (1428590110462443520)]} 0 5
[junit4:junit4] 2> 20882 T581 C30 P55563 /update {version=2&wt=javabin} {add=[55]} 0 8
[junit4:junit4] 2> 20891 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[56 (1428590110473977856)]} 0 2
[junit4:junit4] 2> 20891 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[56 (1428590110473977856)]} 0 2
[junit4:junit4] 2> 20892 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[56 (1428590110473977856)]} 0 6
[junit4:junit4] 2> 20892 T580 C30 P55563 /update {version=2&wt=javabin} {add=[56]} 0 8
[junit4:junit4] 2> 20901 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[57 (1428590110484463616)]} 0 1
[junit4:junit4] 2> 20901 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[57 (1428590110484463616)]} 0 1
[junit4:junit4] 2> 20902 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[57 (1428590110484463616)]} 0 5
[junit4:junit4] 2> 20903 T583 C30 P55563 /update {version=2&wt=javabin} {add=[57]} 0 8
[junit4:junit4] 2> 20911 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[58 (1428590110494949376)]} 0 1
[junit4:junit4] 2> 20912 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[58 (1428590110494949376)]} 0 2
[junit4:junit4] 2> 20912 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[58 (1428590110494949376)]} 0 5
[junit4:junit4] 2> 20913 T582 C30 P55563 /update {version=2&wt=javabin} {add=[58]} 0 8
[junit4:junit4] 2> 20921 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[59 (1428590110505435136)]} 0 1
[junit4:junit4] 2> 20921 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[59 (1428590110505435136)]} 0 1
[junit4:junit4] 2> 20922 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[59 (1428590110505435136)]} 0 5
[junit4:junit4] 2> 20923 T581 C30 P55563 /update {version=2&wt=javabin} {add=[59]} 0 8
[junit4:junit4] 2> 20937 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[60 (1428590110518018048)]} 0 2
[junit4:junit4] 2> 20937 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[60 (1428590110518018048)]} 0 3
[junit4:junit4] 2> 20938 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[60 (1428590110518018048)]} 0 10
[junit4:junit4] 2> 20938 T580 C30 P55563 /update {version=2&wt=javabin} {add=[60]} 0 13
[junit4:junit4] 2> 20949 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[61 (1428590110533746688)]} 0 1
[junit4:junit4] 2> 20950 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[61 (1428590110533746688)]} 0 2
[junit4:junit4] 2> 20950 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[61 (1428590110533746688)]} 0 6
[junit4:junit4] 2> 20951 T583 C30 P55563 /update {version=2&wt=javabin} {add=[61]} 0 10
[junit4:junit4] 2> 20960 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[62 (1428590110546329600)]} 0 1
[junit4:junit4] 2> 20961 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[62 (1428590110546329600)]} 0 2
[junit4:junit4] 2> 20961 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[62 (1428590110546329600)]} 0 6
[junit4:junit4] 2> 20962 T582 C30 P55563 /update {version=2&wt=javabin} {add=[62]} 0 9
[junit4:junit4] 2> 20971 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[63 (1428590110557863936)]} 0 1
[junit4:junit4] 2> 20971 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[63 (1428590110557863936)]} 0 1
[junit4:junit4] 2> 20972 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[63 (1428590110557863936)]} 0 5
[junit4:junit4] 2> 20973 T581 C30 P55563 /update {version=2&wt=javabin} {add=[63]} 0 9
[junit4:junit4] 2> 20983 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[64 (1428590110569398272)]} 0 1
[junit4:junit4] 2> 20983 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[64 (1428590110569398272)]} 0 2
[junit4:junit4] 2> 20983 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[64 (1428590110569398272)]} 0 6
[junit4:junit4] 2> 20984 T580 C30 P55563 /update {version=2&wt=javabin} {add=[64]} 0 9
[junit4:junit4] 2> 20993 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[65 (1428590110579884032)]} 0 2
[junit4:junit4] 2> 20993 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[65 (1428590110579884032)]} 0 2
[junit4:junit4] 2> 20993 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[65 (1428590110579884032)]} 0 5
[junit4:junit4] 2> 20994 T583 C30 P55563 /update {version=2&wt=javabin} {add=[65]} 0 8
[junit4:junit4] 2> 21003 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[66 (1428590110591418368)]} 0 1
[junit4:junit4] 2> 21003 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[66 (1428590110591418368)]} 0 1
[junit4:junit4] 2> 21003 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[66 (1428590110591418368)]} 0 5
[junit4:junit4] 2> 21004 T582 C30 P55563 /update {version=2&wt=javabin} {add=[66]} 0 8
[junit4:junit4] 2> 21013 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[67 (1428590110601904128)]} 0 2
[junit4:junit4] 2> 21013 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[67 (1428590110601904128)]} 0 2
[junit4:junit4] 2> 21014 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[67 (1428590110601904128)]} 0 6
[junit4:junit4] 2> 21014 T581 C30 P55563 /update {version=2&wt=javabin} {add=[67]} 0 8
[junit4:junit4] 2> 21023 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[68 (1428590110612389888)]} 0 1
[junit4:junit4] 2> 21024 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[68 (1428590110612389888)]} 0 2
[junit4:junit4] 2> 21024 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[68 (1428590110612389888)]} 0 5
[junit4:junit4] 2> 21025 T580 C30 P55563 /update {version=2&wt=javabin} {add=[68]} 0 8
[junit4:junit4] 2> 21034 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[69 (1428590110623924224)]} 0 1
[junit4:junit4] 2> 21036 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[69 (1428590110623924224)]} 0 1
[junit4:junit4] 2> 21037 T554 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[69 (1428590110623924224)]} 0 7
[junit4:junit4] 2> 21038 T583 C30 P55563 /update {version=2&wt=javabin} {add=[69]} 0 11
[junit4:junit4] 2> 21047 T580 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[70 (1428590110637555712)]} 0 1
[junit4:junit4] 2> 21047 T570 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[70 (1428590110637555712)]} 0 1
[junit4:junit4] 2> 21047 T557 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[70 (1428590110637555712)]} 0 5
[junit4:junit4] 2> 21048 T582 C30 P55563 /update {version=2&wt=javabin} {add=[70]} 0 8
[junit4:junit4] 2> 21057 T567 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[71 (1428590110648041472)]} 0 1
[junit4:junit4] 2> 21057 T583 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[71 (1428590110648041472)]} 0 1
[junit4:junit4] 2> 21058 T555 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[71 (1428590110648041472)]} 0 5
[junit4:junit4] 2> 21059 T581 C30 P55563 /update {version=2&wt=javabin} {add=[71]} 0 9
[junit4:junit4] 2> 21068 T568 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[72 (1428590110659575808)]} 0 2
[junit4:junit4] 2> 21068 T582 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[72 (1428590110659575808)]} 0 2
[junit4:junit4] 2> 21069 T556 C29 P33124 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:55563/unloadcollection3/} {add=[72 (1428590110659575808)]} 0 5
[junit4:junit4] 2> 21069 T580 C30 P55563 /update {version=2&wt=javabin} {add=[72]} 0 8
[junit4:junit4] 2> 21079 T569 C28 P40210 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1/} {add=[73 (1428590110671110144)]} 0 2
[junit4:junit4] 2> 21079 T581 C30 P55563 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:33124/unloadcollection1
[...truncated too long message...]
ding=UTF-8
[junit4:junit4] ERROR 32.2s J0 | BasicDistributedZk2Test.testDistribSearch <<<
[junit4:junit4] > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:52427/fph/onenodecollectioncore returned non ok status:404, message:Can not find: /fph/onenodecollectioncore/update
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([A7204E4A6F8C7F80:26C6C05218D31FBC]: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:85)
[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> 32178 T1322 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> 32182 T1321 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4:junit4] 2> 33021 T1325 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4] 2> 33388 T1409 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 33388 T1409 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 33388 T1409 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 33388 T1409 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=DefaultSimilarity, locale=ar_LY, timezone=Africa/Bangui
[junit4:junit4] 2> NOTE: Linux 3.2.0-38-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=2,free=43715560,total=125157376
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestSolrDeletionPolicy2, ShowFileRequestHandlerTest, SchemaVersionSpecificBehaviorTest, DateFieldTest, DirectUpdateHandlerOptimizeTest, TestConfig, TestRemoteStreaming, TestIBSimilarityFactory, PreAnalyzedFieldTest, TestSolrDeletionPolicy1, ZkControllerTest, AnalysisAfterCoreReloadTest, CSVRequestHandlerTest, TestPropInjectDefaults, TestHashPartitioner, TestPhraseSuggestions, TestCollationField, BinaryUpdateRequestHandlerTest, TestSurroundQueryParser, TestLFUCache, AlternateDirectoryTest, SOLR749Test, JSONWriterTest, StatelessScriptUpdateProcessorFactoryTest, SpatialFilterTest, SuggesterTSTTest, TestBadConfig, PrimUtilsTest, TestArbitraryIndexDir, TestExtendedDismaxParser, TestFastLRUCache, TestOmitPositions, TestPHPSerializedResponseWriter, LeaderElectionTest, ShardRoutingCustomTest, SolrPluginUtilsTest, TestStressRecovery, TestSweetSpotSimilarityFactory, ZkSolrClientTest, TestQueryTypes, FieldMutatingUpdateProcessorTest, MinimalSchemaTest, TestIndexSearcher, TestFunctionQuery, DirectSolrConnectionTest, UnloadDistributedZkTest, CurrencyFieldOpenExchangeTest, CircularListTest, TestFiltering, TestFoldingMultitermQuery, SolrCoreTest, TestReplicationHandler, TestFuzzyAnalyzedSuggestions, SpellCheckCollatorTest, LukeRequestHandlerTest, TestTrie, SpellingQueryConverterTest, TestXIncludeConfig, SynonymTokenizerTest, BadComponentTest, SystemInfoHandlerTest, CacheHeaderTest, TestSearchPerf, StatsComponentTest, BasicDistributedZk2Test]
[junit4:junit4] Completed on J0 in 33.46s, 1 test, 1 error <<< FAILURES!
[...truncated 404 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: 260 suites, 1094 tests, 1 error, 1 failure, 9 ignored (3 assumptions)
Total time: 41 minutes 23 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:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure