lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b79) - Build # 4819 - Failure!
Date Fri, 22 Mar 2013 05:53:50 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/4819/
Java: 32bit/jdk1.8.0-ea-b79 -client -XX:+UseSerialGC

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

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

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:36098/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
	at __randomizedtesting.SeedInfo.seed([73CCE53B9CE60069:F22A6B23EBB96055]: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:197)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


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

Error Message:
Still found shard2 in collection test_unload_shard_and_collection

Stack Trace:
java.lang.AssertionError: Still found shard2 in collection test_unload_shard_and_collection
	at __randomizedtesting.SeedInfo.seed([73CCE53B9CE60069:F22A6B23EBB96055]: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 8993 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest
[junit4:junit4]   2> 0 T278 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /t_/
[junit4:junit4]   2> 5 T278 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073
[junit4:junit4]   2> 8 T278 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 10 T279 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 65 T279 oaz.Environment.logEnv Server environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4]   2> 66 T279 oaz.Environment.logEnv Server environment:host.name=serv1
[junit4:junit4]   2> 66 T279 oaz.Environment.logEnv Server environment:java.version=1.8.0-ea
[junit4:junit4]   2> 66 T279 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 66 T279 oaz.Environment.logEnv Server environment:java.home=/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b79/jre
[junit4:junit4]   2> 66 T279 oaz.Environment.logEnv Server environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/lucene-codecs-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/cglib-nodep-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/easymock-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/guava-13.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/objenesis-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/org.restlet-2.1.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/org.restlet.ext.servlet-2.1.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpclient-4.2.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpcore-4.2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpmime-4.2.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/noggit-0.5.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/zookeeper-3.4.5.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-continuation-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-http-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-security-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-util-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-webapp-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-xml-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.8.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.3.0.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b79/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.8.jar
[junit4:junit4]   2> 68 T279 oaz.Environment.logEnv Server environment:java.library.path=/usr/java/packages/lib/i386:/lib:/usr/lib
[junit4:junit4]   2> 68 T279 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 68 T279 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4]   2> 69 T279 oaz.Environment.logEnv Server environment:os.name=Linux
[junit4:junit4]   2> 69 T279 oaz.Environment.logEnv Server environment:os.arch=i386
[junit4:junit4]   2> 69 T279 oaz.Environment.logEnv Server environment:os.version=3.2.0-39-generic
[junit4:junit4]   2> 69 T279 oaz.Environment.logEnv Server environment:user.name=jenkins
[junit4:junit4]   2> 69 T279 oaz.Environment.logEnv Server environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 69 T279 oaz.Environment.logEnv Server environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 84 T279 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 85 T279 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 85 T279 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 157 T279 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 200 T279 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1363930487072/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 210 T278 oasc.ZkTestServer.run start zk server on port:52757
[junit4:junit4]   2> 243 T278 oaz.Environment.logEnv Client environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4]   2> 243 T278 oaz.Environment.logEnv Client environment:host.name=serv1
[junit4:junit4]   2> 244 T278 oaz.Environment.logEnv Client environment:java.version=1.8.0-ea
[junit4:junit4]   2> 244 T278 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 244 T278 oaz.Environment.logEnv Client environment:java.home=/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b79/jre
[junit4:junit4]   2> 245 T278 oaz.Environment.logEnv Client environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/lucene-codecs-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/cglib-nodep-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/easymock-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/guava-13.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/objenesis-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/org.restlet-2.1.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/org.restlet.ext.servlet-2.1.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpclient-4.2.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpcore-4.2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpmime-4.2.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/noggit-0.5.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/zookeeper-3.4.5.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-continuation-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-http-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-security-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-util-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-webapp-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-xml-8.1.8.v20121106.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.8.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.3.0.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b79/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.8.jar
[junit4:junit4]   2> 251 T278 oaz.Environment.logEnv Client environment:java.library.path=/usr/java/packages/lib/i386:/lib:/usr/lib
[junit4:junit4]   2> 251 T278 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 252 T278 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4]   2> 252 T278 oaz.Environment.logEnv Client environment:os.name=Linux
[junit4:junit4]   2> 253 T278 oaz.Environment.logEnv Client environment:os.arch=i386
[junit4:junit4]   2> 253 T278 oaz.Environment.logEnv Client environment:os.version=3.2.0-39-generic
[junit4:junit4]   2> 253 T278 oaz.Environment.logEnv Client environment:user.name=jenkins
[junit4:junit4]   2> 254 T278 oaz.Environment.logEnv Client environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 254 T278 oaz.Environment.logEnv Client environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 256 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1122d0
[junit4:junit4]   2> 297 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 300 T284 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 302 T284 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 751 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34411
[junit4:junit4]   2> 760 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34411
[junit4:junit4]   2> 764 T282 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 777 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0000 with negotiated timeout 10000 for client /127.0.0.1:34411
[junit4:junit4]   2> 777 T284 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0000, negotiated timeout = 10000
[junit4:junit4]   2> 781 T285 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1122d0 name:ZooKeeperConnection Watcher:127.0.0.1:52757 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 781 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 783 T278 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 806 T283 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d909799ee0000
[junit4:junit4]   2> 807 T285 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 807 T278 oaz.ZooKeeper.close Session: 0x13d909799ee0000 closed
[junit4:junit4]   2> 808 T280 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:34411 which had sessionid 0x13d909799ee0000
[junit4:junit4]   2> 808 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1418137
[junit4:junit4]   2> 809 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 809 T286 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 810 T286 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 810 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34412
[junit4:junit4]   2> 810 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34412
[junit4:junit4]   2> 811 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0001 with negotiated timeout 10000 for client /127.0.0.1:34412
[junit4:junit4]   2> 811 T286 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0001, negotiated timeout = 10000
[junit4:junit4]   2> 811 T287 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1418137 name:ZooKeeperConnection Watcher:127.0.0.1:52757/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 812 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 814 T278 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 817 T278 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 820 T278 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 822 T278 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 825 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 833 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 838 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 839 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 842 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 842 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 845 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 845 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 848 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 848 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 851 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 851 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 854 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 854 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 857 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 857 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 860 T278 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 860 T278 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 863 T283 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d909799ee0001
[junit4:junit4]   2> 863 T287 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 863 T280 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:34412 which had sessionid 0x13d909799ee0001
[junit4:junit4]   2> 863 T278 oaz.ZooKeeper.close Session: 0x13d909799ee0001 closed
[junit4:junit4]   2> 1078 T278 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 1187 T278 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54174
[junit4:junit4]   2> 1202 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1202 T278 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1202 T278 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1363930487931
[junit4:junit4]   2> 1203 T278 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1363930487931/solr.xml
[junit4:junit4]   2> 1204 T278 oasc.CoreContainer.<init> New CoreContainer 14762936
[junit4:junit4]   2> 1204 T278 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1363930487931/'
[junit4:junit4]   2> 1205 T278 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1363930487931/'
[junit4:junit4]   2> 1251 T278 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 1252 T278 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1252 T278 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 1253 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1253 T278 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1253 T278 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1253 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1254 T278 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1254 T278 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1274 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 1673 T278 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1687 T278 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52757/solr
[junit4:junit4]   2> 1695 T278 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 1696 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@26fdd2
[junit4:junit4]   2> 1696 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1698 T298 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1698 T298 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 1699 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34413
[junit4:junit4]   2> 1699 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34413
[junit4:junit4]   2> 1701 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0002 with negotiated timeout 20000 for client /127.0.0.1:34413
[junit4:junit4]   2> 1701 T298 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0002, negotiated timeout = 20000
[junit4:junit4]   2> 1701 T299 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@26fdd2 name:ZooKeeperConnection Watcher:127.0.0.1:52757 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1702 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1703 T283 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d909799ee0002
[junit4:junit4]   2> 1710 T299 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1710 T280 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:34413 which had sessionid 0x13d909799ee0002
[junit4:junit4]   2> 1710 T278 oaz.ZooKeeper.close Session: 0x13d909799ee0002 closed
[junit4:junit4]   2> 1711 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 1717 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f2e7ac
[junit4:junit4]   2> 1718 T300 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1718 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1719 T300 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 1719 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34414
[junit4:junit4]   2> 1719 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34414
[junit4:junit4]   2> 1721 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0003 with negotiated timeout 20000 for client /127.0.0.1:34414
[junit4:junit4]   2> 1721 T300 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0003, negotiated timeout = 20000
[junit4:junit4]   2> 1721 T301 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f2e7ac name:ZooKeeperConnection Watcher:127.0.0.1:52757/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1721 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1727 T278 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4:junit4]   2> 1734 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0003 type:create cxid:0x7 zxid:0x1b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1735 T278 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4:junit4]   2> 1744 T278 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 1746 T278 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54174_t_
[junit4:junit4]   2> 1749 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0003 type:delete cxid:0x12 zxid:0x1e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:54174_t_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:54174_t_
[junit4:junit4]   2> 1750 T278 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54174_t_
[junit4:junit4]   2> 1755 T278 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 1773 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0003 type:delete cxid:0x20 zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 1780 T278 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 1783 T278 oasc.Overseer.start Overseer (id=89386548414251011-127.0.0.1:54174_t_-n_0000000000) starting
[junit4:junit4]   2> 1784 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0003 type:create cxid:0x25 zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1790 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0003 type:create cxid:0x26 zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1791 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0003 type:create cxid:0x28 zxid:0x27 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1792 T278 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4:junit4]   2> 1799 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0003 type:create cxid:0x2e zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1800 T303 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 1801 T278 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 1803 T278 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4:junit4]   2> 1807 T278 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1814 T302 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 1826 T278 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 1826 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1840 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1846 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 1847 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@776325
[junit4:junit4]   2> 1848 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1848 T305 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1849 T305 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 1849 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34415
[junit4:junit4]   2> 1849 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34415
[junit4:junit4]   2> 1850 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0004 with negotiated timeout 10000 for client /127.0.0.1:34415
[junit4:junit4]   2> 1850 T305 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0004, negotiated timeout = 10000
[junit4:junit4]   2> 1850 T306 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@776325 name:ZooKeeperConnection Watcher:127.0.0.1:52757/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1850 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1852 T278 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1855 T278 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 1917 T278 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 1919 T278 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:42736
[junit4:junit4]   2> 1919 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1920 T278 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1920 T278 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922
[junit4:junit4]   2> 1920 T278 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922/solr.xml
[junit4:junit4]   2> 1921 T278 oasc.CoreContainer.<init> New CoreContainer 24921510
[junit4:junit4]   2> 1921 T278 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922/'
[junit4:junit4]   2> 1921 T278 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922/'
[junit4:junit4]   2> 1947 T278 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 1947 T278 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1948 T278 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 1948 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1948 T278 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1949 T278 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1949 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1949 T278 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1950 T278 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1950 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 1959 T278 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1977 T278 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52757/solr
[junit4:junit4]   2> 1978 T278 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 1978 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1665a18
[junit4:junit4]   2> 1979 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1979 T316 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1980 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34416
[junit4:junit4]   2> 1980 T316 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 1981 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34416
[junit4:junit4]   2> 1981 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0005 with negotiated timeout 20000 for client /127.0.0.1:34416
[junit4:junit4]   2> 1981 T316 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0005, negotiated timeout = 20000
[junit4:junit4]   2> 1982 T317 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1665a18 name:ZooKeeperConnection Watcher:127.0.0.1:52757 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1982 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1983 T283 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d909799ee0005
[junit4:junit4]   2> 1984 T317 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1984 T280 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:34416 which had sessionid 0x13d909799ee0005
[junit4:junit4]   2> 1984 T278 oaz.ZooKeeper.close Session: 0x13d909799ee0005 closed
[junit4:junit4]   2> 1984 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 1988 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@639f55
[junit4:junit4]   2> 1989 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1989 T318 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1990 T318 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 1990 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34417
[junit4:junit4]   2> 1990 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34417
[junit4:junit4]   2> 1991 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0006 with negotiated timeout 20000 for client /127.0.0.1:34417
[junit4:junit4]   2> 1991 T318 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0006, negotiated timeout = 20000
[junit4:junit4]   2> 1991 T319 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@639f55 name:ZooKeeperConnection Watcher:127.0.0.1:52757/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1991 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1992 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0006 type:create cxid:0x1 zxid:0x30 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1994 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0006 type:create cxid:0x3 zxid:0x31 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1996 T278 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2999 T278 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42736_t_
[junit4:junit4]   2> 3000 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0006 type:delete cxid:0xd zxid:0x32 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42736_t_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42736_t_
[junit4:junit4]   2> 3001 T278 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42736_t_
[junit4:junit4]   2> 3006 T301 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 3006 T306 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 3006 T319 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 3012 T278 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3012 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3012 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3077 T278 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3079 T278 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50484
[junit4:junit4]   2> 3080 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3080 T278 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3080 T278 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082
[junit4:junit4]   2> 3081 T278 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082/solr.xml
[junit4:junit4]   2> 3081 T278 oasc.CoreContainer.<init> New CoreContainer 3398184
[junit4:junit4]   2> 3081 T278 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082/'
[junit4:junit4]   2> 3082 T278 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082/'
[junit4:junit4]   2> 3108 T278 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 3108 T278 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3108 T278 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 3109 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3109 T278 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3109 T278 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3110 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3110 T278 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3110 T278 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3111 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3118 T278 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3131 T278 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52757/solr
[junit4:junit4]   2> 3131 T278 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3132 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1796d05
[junit4:junit4]   2> 3132 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3136 T330 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3137 T330 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 3137 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34419
[junit4:junit4]   2> 3137 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34419
[junit4:junit4]   2> 3139 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0007 with negotiated timeout 20000 for client /127.0.0.1:34419
[junit4:junit4]   2> 3139 T330 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0007, negotiated timeout = 20000
[junit4:junit4]   2> 3139 T331 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1796d05 name:ZooKeeperConnection Watcher:127.0.0.1:52757 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3139 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3141 T283 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d909799ee0007
[junit4:junit4]   2> 3141 T280 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:34419 which had sessionid 0x13d909799ee0007
[junit4:junit4]   2> 3141 T331 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3141 T278 oaz.ZooKeeper.close Session: 0x13d909799ee0007 closed
[junit4:junit4]   2> 3142 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3145 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@10c3561
[junit4:junit4]   2> 3146 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3146 T332 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3146 T332 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 3146 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34420
[junit4:junit4]   2> 3147 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34420
[junit4:junit4]   2> 3147 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0008 with negotiated timeout 20000 for client /127.0.0.1:34420
[junit4:junit4]   2> 3147 T332 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0008, negotiated timeout = 20000
[junit4:junit4]   2> 3148 T333 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10c3561 name:ZooKeeperConnection Watcher:127.0.0.1:52757/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3148 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3149 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0008 type:create cxid:0x1 zxid:0x38 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3150 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0008 type:create cxid:0x3 zxid:0x39 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3152 T278 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4155 T278 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50484_t_
[junit4:junit4]   2> 4155 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0008 type:delete cxid:0xd zxid:0x3a txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50484_t_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50484_t_
[junit4:junit4]   2> 4157 T278 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50484_t_
[junit4:junit4]   2> 4160 T306 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4161 T319 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4161 T301 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4162 T333 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4167 T278 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 4168 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4168 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4245 T278 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 4248 T278 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46397
[junit4:junit4]   2> 4249 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4249 T278 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4249 T278 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239
[junit4:junit4]   2> 4250 T278 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239/solr.xml
[junit4:junit4]   2> 4250 T278 oasc.CoreContainer.<init> New CoreContainer 31186681
[junit4:junit4]   2> 4250 T278 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239/'
[junit4:junit4]   2> 4251 T278 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239/'
[junit4:junit4]   2> 4274 T278 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 4275 T278 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4275 T278 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 4275 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4276 T278 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4276 T278 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4277 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4277 T278 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4278 T278 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4278 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 4290 T278 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4309 T278 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52757/solr
[junit4:junit4]   2> 4310 T278 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 4310 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f2a68f
[junit4:junit4]   2> 4311 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4311 T344 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4312 T344 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 4313 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34421
[junit4:junit4]   2> 4313 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34421
[junit4:junit4]   2> 4314 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee0009 with negotiated timeout 20000 for client /127.0.0.1:34421
[junit4:junit4]   2> 4314 T344 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee0009, negotiated timeout = 20000
[junit4:junit4]   2> 4314 T345 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f2a68f name:ZooKeeperConnection Watcher:127.0.0.1:52757 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4314 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4316 T283 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d909799ee0009
[junit4:junit4]   2> 4317 T280 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:34421 which had sessionid 0x13d909799ee0009
[junit4:junit4]   2> 4317 T345 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 4317 T278 oaz.ZooKeeper.close Session: 0x13d909799ee0009 closed
[junit4:junit4]   2> 4318 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 4322 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@68f97c
[junit4:junit4]   2> 4324 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4332 T346 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4332 T346 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 4332 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34422
[junit4:junit4]   2> 4333 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34422
[junit4:junit4]   2> 4334 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee000a with negotiated timeout 20000 for client /127.0.0.1:34422
[junit4:junit4]   2> 4334 T346 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee000a, negotiated timeout = 20000
[junit4:junit4]   2> 4334 T347 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@68f97c name:ZooKeeperConnection Watcher:127.0.0.1:52757/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4334 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4336 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee000a type:create cxid:0x1 zxid:0x40 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4337 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee000a type:create cxid:0x3 zxid:0x41 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4340 T278 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5343 T278 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46397_t_
[junit4:junit4]   2> 5344 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee000a type:delete cxid:0xd zxid:0x42 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:46397_t_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:46397_t_
[junit4:junit4]   2> 5346 T278 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46397_t_
[junit4:junit4]   2> 5349 T306 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5349 T319 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5349 T347 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5350 T301 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5350 T333 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5355 T278 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5355 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5355 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5425 T278 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 5427 T278 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49154
[junit4:junit4]   2> 5428 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5428 T278 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5428 T278 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1363930492426
[junit4:junit4]   2> 5428 T278 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1363930492426/solr.xml
[junit4:junit4]   2> 5429 T278 oasc.CoreContainer.<init> New CoreContainer 5293659
[junit4:junit4]   2> 5429 T278 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1363930492426/'
[junit4:junit4]   2> 5429 T278 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1363930492426/'
[junit4:junit4]   2> 5450 T278 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 5450 T278 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5451 T278 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 5451 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5451 T278 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5452 T278 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5452 T278 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5452 T278 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5452 T278 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5453 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 5464 T278 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5476 T278 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52757/solr
[junit4:junit4]   2> 5477 T278 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 5477 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@5f8518
[junit4:junit4]   2> 5478 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5478 T358 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 5479 T358 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 5479 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34423
[junit4:junit4]   2> 5480 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34423
[junit4:junit4]   2> 5480 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee000b with negotiated timeout 20000 for client /127.0.0.1:34423
[junit4:junit4]   2> 5480 T358 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee000b, negotiated timeout = 20000
[junit4:junit4]   2> 5481 T359 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5f8518 name:ZooKeeperConnection Watcher:127.0.0.1:52757 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5481 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5482 T283 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d909799ee000b
[junit4:junit4]   2> 5483 T280 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:34423 which had sessionid 0x13d909799ee000b
[junit4:junit4]   2> 5483 T359 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 5483 T278 oaz.ZooKeeper.close Session: 0x13d909799ee000b closed
[junit4:junit4]   2> 5484 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 5487 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@d54d20
[junit4:junit4]   2> 5488 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5489 T360 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 5489 T360 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 5489 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34424
[junit4:junit4]   2> 5493 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34424
[junit4:junit4]   2> 5494 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee000c with negotiated timeout 20000 for client /127.0.0.1:34424
[junit4:junit4]   2> 5494 T360 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee000c, negotiated timeout = 20000
[junit4:junit4]   2> 5494 T361 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d54d20 name:ZooKeeperConnection Watcher:127.0.0.1:52757/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5494 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5495 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee000c type:create cxid:0x1 zxid:0x48 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5497 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee000c type:create cxid:0x3 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5500 T278 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6503 T278 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49154_t_
[junit4:junit4]   2> 6504 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee000c type:delete cxid:0xd zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49154_t_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49154_t_
[junit4:junit4]   2> 6505 T278 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49154_t_
[junit4:junit4]   2> 6508 T301 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6508 T347 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6509 T319 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6515 T333 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6515 T306 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6516 T361 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 6521 T278 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 6521 T278 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6521 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6524 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6803 T315 oasc.CoreContainer.create Creating SolrCore 'unloadcollection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922/unloadcollection1
[junit4:junit4]   2> 6803 T315 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 6804 T315 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:unloadcollection
[junit4:junit4]   2> 6805 T315 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 6807 T315 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 6807 T315 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection
[junit4:junit4]   2> 6812 T315 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 6818 T315 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922/unloadcollection1/'
[junit4:junit4]   2> 6925 T315 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 6973 T315 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6974 T315 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6985 T315 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7484 T315 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7497 T315 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7500 T315 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7521 T315 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7527 T315 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7531 T315 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7533 T315 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 7533 T315 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 7534 T315 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7535 T315 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 7535 T315 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 7535 T315 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 7536 T315 oasc.SolrCore.<init> [unloadcollection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922/unloadcollection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930493598unloadcollection1_1n/
[junit4:junit4]   2> 7536 T315 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e8177b
[junit4:junit4]   2> 7537 T315 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 7539 T315 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930493598unloadcollection1_1n
[junit4:junit4]   2> 7539 T315 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930493598unloadcollection1_1n/index/
[junit4:junit4]   2> 7540 T315 oasc.SolrCore.initIndex WARNING [unloadcollection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930493598unloadcollection1_1n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7540 T315 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930493598unloadcollection1_1n/index
[junit4:junit4]   2> 7542 T315 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@faebde lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a668f2),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7542 T315 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 7544 T315 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7544 T315 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7545 T315 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7545 T315 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7546 T315 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7546 T315 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7546 T315 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7547 T315 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7547 T315 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7551 T315 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7555 T315 oass.SolrIndexSearcher.<init> Opening Searcher@a21995 main
[junit4:junit4]   2> 7555 T315 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930493598unloadcollection1_1n/tlog
[junit4:junit4]   2> 7556 T315 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7556 T315 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7561 T363 oasc.SolrCore.registerSearcher [unloadcollection1] Registered new searcher Searcher@a21995 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7563 T315 oasc.ZkController.publish publishing core=unloadcollection1 state=down
[junit4:junit4]   2> 7824 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7825 T302 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42736/t_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42736_t_",
[junit4:junit4]   2> 	  "core":"unloadcollection1"}
[junit4:junit4]   2> 7826 T302 oasc.Overseer$ClusterStateUpdater.createCollection Create collection unloadcollection with numShards 1
[junit4:junit4]   2> 7833 T302 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 7846 T347 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> 7846 T319 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> 7846 T333 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> 7846 T306 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> 7846 T301 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> 7846 T361 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> 8566 T315 oasc.CoreContainer.registerCore registering core: unloadcollection1
[junit4:junit4]   2> 8566 T315 oasc.ZkController.register Register replica - core:unloadcollection1 address:http://127.0.0.1:42736/t_ collection:unloadcollection shard:shard1
[junit4:junit4]   2> 8570 T315 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 8575 T315 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leader_elect/shard1/election
[junit4:junit4]   2> 8583 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0006 type:delete cxid:0x51 zxid:0x57 txntype:-1 reqpath:n/a Error Path:/solr/collections/unloadcollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/unloadcollection/leaders
[junit4:junit4]   2> 8583 T315 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 8584 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0006 type:create cxid:0x52 zxid:0x58 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8587 T315 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 8588 T315 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 8588 T315 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:42736/t_/unloadcollection1/
[junit4:junit4]   2> 8589 T315 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 8589 T315 oasc.SyncStrategy.syncToMe http://127.0.0.1:42736/t_/unloadcollection1/ has no replicas
[junit4:junit4]   2> 8590 T315 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42736/t_/unloadcollection1/
[junit4:junit4]   2> 8590 T315 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leaders/shard1
[junit4:junit4]   2> 8595 T283 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d909799ee0006 type:create cxid:0x5d zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9352 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9363 T333 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> 9363 T306 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> 9363 T301 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> 9363 T347 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> 9363 T361 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> 9363 T319 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> 9399 T315 oasc.ZkController.register We are http://127.0.0.1:42736/t_/unloadcollection1/ and leader is http://127.0.0.1:42736/t_/unloadcollection1/
[junit4:junit4]   2> 9399 T315 oasc.ZkController.register No LogReplay needed for core=unloadcollection1 baseURL=http://127.0.0.1:42736/t_
[junit4:junit4]   2> 9400 T315 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 9400 T315 oasc.ZkController.publish publishing core=unloadcollection1 state=active
[junit4:junit4]   2> 9449 T315 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9450 T315 oasc.CoreContainer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922/solr.xml
[junit4:junit4]   2> 9461 T315 oasc.SolrXMLSerializer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1363930488922/solr.xml
[junit4:junit4]   2> 9463 T315 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {collection=unloadcollection&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930493598unloadcollection1_1n&name=unloadcollection1&numShards=1&version=2&wt=javabin&action=CREATE} status=0 QTime=2664 
[junit4:junit4]   2> 9498 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 9500 T278 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52757/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@c39c09
[junit4:junit4]   2> 9501 T278 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 9501 T364 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:52757. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 9502 T364 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:52757, initiating session
[junit4:junit4]   2> 9502 T280 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:34428
[junit4:junit4]   2> 9502 T280 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:34428
[junit4:junit4]   2> 9504 T282 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d909799ee000d with negotiated timeout 10000 for client /127.0.0.1:34428
[junit4:junit4]   2> 9504 T364 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:52757, sessionid = 0x13d909799ee000d, negotiated timeout = 10000
[junit4:junit4]   2> 9504 T365 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c39c09 name:ZooKeeperConnection Watcher:127.0.0.1:52757/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 9505 T278 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 9506 T278 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9508 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9509 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9517 T329 oasc.CoreContainer.create Creating SolrCore 'unloadcollection2' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082/unloadcollection2
[junit4:junit4]   2> 9517 T329 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 9518 T329 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9518 T329 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 9519 T329 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082/unloadcollection2/'
[junit4:junit4]   2> 9559 T329 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 9611 T329 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9613 T329 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9618 T329 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 10083 T329 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 10091 T329 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10094 T329 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10109 T329 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10113 T329 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10121 T329 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10122 T329 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 10124 T329 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 10124 T329 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10125 T329 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 10125 T329 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 10126 T329 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 10126 T329 oasc.SolrCore.<init> [unloadcollection2] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082/unloadcollection2/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930496582unloadcollection1_2n/
[junit4:junit4]   2> 10127 T329 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e8177b
[junit4:junit4]   2> 10127 T329 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 10128 T329 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930496582unloadcollection1_2n
[junit4:junit4]   2> 10128 T329 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930496582unloadcollection1_2n/index/
[junit4:junit4]   2> 10128 T329 oasc.SolrCore.initIndex WARNING [unloadcollection2] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930496582unloadcollection1_2n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 10129 T329 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930496582unloadcollection1_2n/index
[junit4:junit4]   2> 10130 T329 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@19fd74 lockFactory=org.apache.lucene.store.NativeFSLockFactory@96bf42),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10130 T329 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10132 T329 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 10132 T329 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 10133 T329 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 10134 T329 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10134 T329 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10134 T329 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 10135 T329 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 10135 T329 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10135 T329 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10138 T329 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10141 T329 oass.SolrIndexSearcher.<init> Opening Searcher@145e72b main
[junit4:junit4]   2> 10141 T329 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930496582unloadcollection1_2n/tlog
[junit4:junit4]   2> 10142 T329 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10142 T329 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10146 T366 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@145e72b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10146 T329 oasc.ZkController.publish publishing core=unloadcollection2 state=down
[junit4:junit4]   2> 10146 T329 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10876 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10877 T302 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42736/t_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:42736_t__unloadcollection1",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42736_t_",
[junit4:junit4]   2> 	  "core":"unloadcollection1"}
[junit4:junit4]   2> 10882 T302 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50484/t_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50484_t_",
[junit4:junit4]   2> 	  "core":"unloadcollection2"}
[junit4:junit4]   2> 10882 T302 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 10882 T302 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 10886 T365 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> 10886 T306 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> 10886 T319 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> 10887 T361 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> 10886 T333 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> 10886 T347 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> 10887 T301 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> 11148 T329 oasc.CoreContainer.registerCore registering core: unloadcollection2
[junit4:junit4]   2> 11148 T329 oasc.ZkController.register Register replica - core:unloadcollection2 address:http://127.0.0.1:50484/t_ collection:unloadcollection shard:shard1
[junit4:junit4]   2> 11152 T329 oasc.ZkController.register We are http://127.0.0.1:50484/t_/unloadcollection2/ and leader is http://127.0.0.1:42736/t_/unloadcollection1/
[junit4:junit4]   2> 11152 T329 oasc.ZkController.register No LogReplay needed for core=unloadcollection2 baseURL=http://127.0.0.1:50484/t_
[junit4:junit4]   2> 11153 T329 oasc.ZkController.checkRecovery Core needs to recover:unloadcollection2
[junit4:junit4]   2> 11153 T329 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C2 name=unloadcollection2 org.apache.solr.core.SolrCore@6fa14 url=http://127.0.0.1:50484/t_/unloadcollection2 node=127.0.0.1:50484_t_ C2_STATE=coll:unloadcollection core:unloadcollection2 props:{base_url=http://127.0.0.1:50484/t_, collection=unloadcollection, state=down, node_name=127.0.0.1:50484_t_, core=unloadcollection2}
[junit4:junit4]   2> 11158 T367 C2 P50484 oasc.RecoveryStrategy.run Starting recovery process.  core=unloadcollection2 recoveringAfterStartup=true
[junit4:junit4]   2> 11159 T329 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11159 T367 C2 P50484 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 11160 T367 C2 P50484 oasc.ZkController.publish publishing core=unloadcollection2 state=recovering
[junit4:junit4]   2> 11160 T367 C2 P50484 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11160 T329 oasc.CoreContainer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082/solr.xml
[junit4:junit4]   2> 11161 T367 C2 P50484 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 11172 T329 oasc.SolrXMLSerializer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1363930490082/solr.xml
[junit4:junit4]   2> 11173 T329 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {collection=unloadcollection&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930496582unloadcollection1_2n&name=unloadcollection2&version=2&wt=javabin&action=CREATE} status=0 QTime=1656 
[junit4:junit4]   2> 11175 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11176 T278 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: unloadcollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 11177 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12179 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12394 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12395 T302 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50484/t_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:50484_t__unloadcollection2",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50484_t_",
[junit4:junit4]   2> 	  "core":"unloadcollection2"}
[junit4:junit4]   2> 12408 T347 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> 12408 T365 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> 12408 T306 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> 12408 T319 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> 12408 T333 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> 12408 T361 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> 12408 T301 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> 13170 T313 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {coreNodeName=127.0.0.1:50484_t__unloadcollection2&checkLive=true&onlyIfLeader=true&state=recovering&version=2&wt=javabin&nodeName=127.0.0.1:50484_t_&core=unloadcollection1&action=PREPRECOVERY} status=0 QTime=2001 
[junit4:junit4]   2> 13181 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14187 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C2_STATE=coll:unloadcollection core:unloadcollection2 props:{base_url=http://127.0.0.1:50484/t_, collection=unloadcollection, state=recovering, shard=shard1, node_name=127.0.0.1:50484_t_, core=unloadcollection2}
[junit4:junit4]   2> 15171 T367 C2 P50484 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:42736/t_/unloadcollection1/ core=unloadcollection2 - recoveringAfterStartup=true
[junit4:junit4]   2> 15175 T367 C2 P50484 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 15180 T367 C2 P50484 oasu.PeerSync.sync PeerSync: core=unloadcollection2 url=http://127.0.0.1:50484/t_ START replicas=[http://127.0.0.1:42736/t_/unloadcollection1/] nUpdates=100
[junit4:junit4]   2> 15184 T367 C2 P50484 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 15185 T367 C2 P50484 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=unloadcollection2
[junit4:junit4]   2> 15185 T367 C2 P50484 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=unloadcollection2
[junit4:junit4]   2> 15185 T367 C2 P50484 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=unloadcollection2
[junit4:junit4]   2> 15185 T367 C2 P50484 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 15185 T367 C2 P50484 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:42736/t_/unloadcollection1/. core=unloadcollection2
[junit4:junit4]   2> 15186 T367 C2 P50484 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 15188 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C3 name=unloadcollection1 org.apache.solr.core.SolrCore@b65dfb url=http://127.0.0.1:42736/t_/unloadcollection1 node=127.0.0.1:42736_t_ C3_STATE=coll:unloadcollection core:unloadcollection1 props:{base_url=http://127.0.0.1:42736/t_, collection=unloadcollection, state=active, shard=shard1, node_name=127.0.0.1:42736_t_, core=unloadcollection1, leader=true}
[junit4:junit4]   2> 15206 T312 C3 P42736 REQ /get {getVersions=100&distrib=false&version=2&wt=javabin&qt=/get} status=0 QTime=2 
[junit4:junit4]   2> 15212 T314 C3 P42736 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 15214 T314 C3 P42736 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@faebde lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a668f2),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 15214 T314 C3 P42736 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 15215 T314 C3 P42736 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@faebde lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a668f2),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@faebde lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a668f2),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 15215 T314 C3 P42736 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 15216 T314 C3 P42736 oass.SolrIndexSearcher.<init> Opening Searcher@fdac89 realtime
[junit4:junit4]   2> 15216 T314 C3 P42736 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15217 T314 C3 P42736 /update {openSearcher=false&softCommit=false&commit=true&commit_end_point=true&version=2&wt=javabin&waitSearcher=true} {commit=} 0 13
[junit4:junit4]   2> 15236 T367 C2 P50484 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 15237 T367 C2 P50484 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 15241 T367 C2 P50484 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 15260 T313 C3 P42736 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 15260 T313 C3 P42736 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=16 
[junit4:junit4]   2> 15261 T367 C2 P50484 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 15262 T367 C2 P50484 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 15262 T367 C2 P50484 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 15264 T315 C3 P42736 REQ /replication {version=2&command=filelist&generation=2&qt=/replication&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 15265 T367 C2 P50484 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 15266 T367 C2 P50484 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930496582unloadcollection1_2n/index.20130322173502332
[junit4:junit4]   2> 15266 T367 C2 P50484 oash.SnapPuller.fetchLatestIndex Starting download to BaseDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@2d48b4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@127bab8; maxCacheMB=0.9658203125 maxMergeSizeMB=0.68359375)) fullCopy=false
[junit4:junit4]   2> 15278 T312 C3 P42736 REQ /replication {file=segments_2&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication} status=0 QTime=2 
[junit4:junit4]   2> 15282 T367 C2 P50484 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 15289 T367 C2 P50484 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 15289 T367 C2 P50484 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=unloadcollection2
[junit4:junit4]   2> 15290 T367 C2 P50484 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@19fd74 lockFactory=org.apache.lucene.store.NativeFSLockFactory@96bf42),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@19fd74 lockFactory=org.apache.lucene.store.NativeFSLockFactory@96bf42),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 15291 T367 C2 P50484 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 15291 T367 C2 P50484 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 15291 T367 C2 P50484 oass.SolrIndexSearcher.<init> Opening Searcher@1e23796 main
[junit4:junit4]   2> 15292 T366 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@1e23796 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 15293 T367 C2 P50484 oasc.RecoveryStrategy.replay No replay needed. core=unloadcollection2
[junit4:junit4]   2> 15293 T367 C2 P50484 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=unloadcollection2
[junit4:junit4]   2> 15293 T367 C2 P50484 oasc.ZkController.publish publishing core=unloadcollection2 state=active
[junit4:junit4]   2> 15293 T367 C2 P50484 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15294 T367 C2 P50484 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=unloadcollection2
[junit4:junit4]   2> 15416 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15417 T302 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:50484/t_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:50484_t__unloadcollection2",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:50484_t_",
[junit4:junit4]   2> 	  "core":"unloadcollection2"}
[junit4:junit4]   2> 15420 T319 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> 15420 T333 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> 15420 T365 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> 15420 T306 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> 15421 T361 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> 15420 T347 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> 15420 T301 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> 16190 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16191 T278 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: unloadcollection
[junit4:junit4]   2> 16192 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16196 T340 oasc.CoreContainer.create Creating SolrCore 'unloadcollection3' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239/unloadcollection3
[junit4:junit4]   2> 16197 T340 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 16198 T340 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 16198 T340 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 16200 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239/unloadcollection3/'
[junit4:junit4]   2> 16251 T340 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 16319 T340 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 16320 T340 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 16329 T340 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 16852 T340 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 16859 T340 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 16862 T340 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 16878 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 16881 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 16885 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 16887 T340 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 16887 T340 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 16887 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 16888 T340 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 16889 T340 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 16889 T340 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 16889 T340 oasc.SolrCore.<init> [unloadcollection3] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239/unloadcollection3/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930503261unloadcollection_3n/
[junit4:junit4]   2> 16890 T340 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e8177b
[junit4:junit4]   2> 16890 T340 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 16890 T340 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930503261unloadcollection_3n
[junit4:junit4]   2> 16891 T340 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930503261unloadcollection_3n/index/
[junit4:junit4]   2> 16891 T340 oasc.SolrCore.initIndex WARNING [unloadcollection3] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930503261unloadcollection_3n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 16891 T340 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930503261unloadcollection_3n/index
[junit4:junit4]   2> 16893 T340 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f99c24 lockFactory=org.apache.lucene.store.NativeFSLockFactory@fd70bf),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 16893 T340 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 16894 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 16895 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 16895 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 16896 T340 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 16896 T340 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 16896 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 16897 T340 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 16897 T340 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 16898 T340 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 16900 T340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 16907 T340 oass.SolrIndexSearcher.<init> Opening Searcher@86a69c main
[junit4:junit4]   2> 16907 T340 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930503261unloadcollection_3n/tlog
[junit4:junit4]   2> 16908 T340 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 16908 T340 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 16912 T370 oasc.SolrCore.registerSearcher [unloadcollection3] Registered new searcher Searcher@86a69c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 16913 T340 oasc.ZkController.publish publishing core=unloadcollection3 state=down
[junit4:junit4]   2> 16913 T340 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16926 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16927 T302 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46397/t_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46397_t_",
[junit4:junit4]   2> 	  "core":"unloadcollection3"}
[junit4:junit4]   2> 16927 T302 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 16927 T302 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 16931 T365 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> 16931 T347 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> 16932 T306 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> 16931 T361 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> 16931 T301 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> 16932 T333 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> 16932 T319 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> 17914 T340 oasc.CoreContainer.registerCore registering core: unloadcollection3
[junit4:junit4]   2> 17915 T340 oasc.ZkController.register Register replica - core:unloadcollection3 address:http://127.0.0.1:46397/t_ collection:unloadcollection shard:shard1
[junit4:junit4]   2> 17919 T340 oasc.ZkController.register We are http://127.0.0.1:46397/t_/unloadcollection3/ and leader is http://127.0.0.1:42736/t_/unloadcollection1/
[junit4:junit4]   2> 17919 T340 oasc.ZkController.register No LogReplay needed for core=unloadcollection3 baseURL=http://127.0.0.1:46397/t_
[junit4:junit4]   2> 17919 T340 oasc.ZkController.checkRecovery Core needs to recover:unloadcollection3
[junit4:junit4]   2> 17919 T340 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C4 name=unloadcollection3 org.apache.solr.core.SolrCore@1907334 url=http://127.0.0.1:46397/t_/unloadcollection3 node=127.0.0.1:46397_t_ C4_STATE=coll:unloadcollection core:unloadcollection3 props:{base_url=http://127.0.0.1:46397/t_, collection=unloadcollection, state=down, node_name=127.0.0.1:46397_t_, core=unloadcollection3}
[junit4:junit4]   2> 17920 T371 C4 P46397 oasc.RecoveryStrategy.run Starting recovery process.  core=unloadcollection3 recoveringAfterStartup=true
[junit4:junit4]   2> 17920 T371 C4 P46397 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 17921 T371 C4 P46397 oasc.ZkController.publish publishing core=unloadcollection3 state=recovering
[junit4:junit4]   2> 17920 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17921 T371 C4 P46397 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 17922 T340 oasc.CoreContainer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239/solr.xml
[junit4:junit4]   2> 17922 T371 C4 P46397 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 17929 T340 oasc.SolrXMLSerializer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1363930491239/solr.xml
[junit4:junit4]   2> 17931 T340 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {collection=unloadcollection&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930503261unloadcollection_3n&name=unloadcollection3&version=2&wt=javabin&action=CREATE} status=0 QTime=1735 
[junit4:junit4]   2> 17931 T278 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: unloadcollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 17932 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18437 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18438 T302 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46397/t_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:46397_t__unloadcollection3",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46397_t_",
[junit4:junit4]   2> 	  "core":"unloadcollection3"}
[junit4:junit4]   2> 18442 T333 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> 18442 T365 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> 18442 T301 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> 18442 T347 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> 18442 T319 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> 18442 T306 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> 18442 T361 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> 18929 T313 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {coreNodeName=127.0.0.1:46397_t__unloadcollection3&checkLive=true&onlyIfLeader=true&state=recovering&version=2&wt=javabin&nodeName=127.0.0.1:46397_t_&core=unloadcollection1&action=PREPRECOVERY} status=0 QTime=1000 
[junit4:junit4]   2> 18934 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19935 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C4_STATE=coll:unloadcollection core:unloadcollection3 props:{base_url=http://127.0.0.1:46397/t_, collection=unloadcollection, state=recovering, shard=shard1, node_name=127.0.0.1:46397_t_, core=unloadcollection3}
[junit4:junit4]   2> 20930 T371 C4 P46397 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:42736/t_/unloadcollection1/ core=unloadcollection3 - recoveringAfterStartup=true
[junit4:junit4]   2> 20930 T371 C4 P46397 oasu.PeerSync.sync PeerSync: core=unloadcollection3 url=http://127.0.0.1:46397/t_ START replicas=[http://127.0.0.1:42736/t_/unloadcollection1/] nUpdates=100
[junit4:junit4]   2> 20931 T371 C4 P46397 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 20931 T371 C4 P46397 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=unloadcollection3
[junit4:junit4]   2> 20931 T371 C4 P46397 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=unloadcollection3
[junit4:junit4]   2> 20931 T371 C4 P46397 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=unloadcollection3
[junit4:junit4]   2> 20931 T371 C4 P46397 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 20932 T371 C4 P46397 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:42736/t_/unloadcollection1/. core=unloadcollection3
[junit4:junit4]   2> 20932 T371 C4 P46397 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 20933 T313 C3 P42736 REQ /get {getVersions=100&distrib=false&version=2&wt=javabin&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 20937 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20938 T312 C3 P42736 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 20939 T312 C3 P42736 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@faebde lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a668f2),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@faebde lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a668f2),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 20939 T312 C3 P42736 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 20939 T312 C3 P42736 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 20940 T312 C3 P42736 /update {openSearcher=false&softCommit=false&commit=true&commit_end_point=true&version=2&wt=javabin&waitSearcher=true} {commit=} 0 3
[junit4:junit4]   2> 20940 T371 C4 P46397 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 20941 T371 C4 P46397 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 20943 T313 C3 P42736 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 20944 T371 C4 P46397 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4:junit4]   2> 20944 T371 C4 P46397 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 20944 T371 C4 P46397 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 20946 T315 C3 P42736 REQ /replication {version=2&command=filelist&generation=3&qt=/replication&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 20947 T371 C4 P46397 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 20948 T371 C4 P46397 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1363930487073/1363930503261unloadcollection_3n/index.20130322173508014
[junit4:junit4]   2> 20948 T371 C4 P46397 oash.SnapPuller.fetchLatestIndex Starting download to BaseDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@3e790 lockFactory=org.apache.lucene.store.NativeFSLockFactory@9ef7d; maxCacheMB=0.9658203125 maxMergeSizeMB=0.68359375)) fullCopy=false
[junit4:junit4]   2> 20951 T312 C3 P42736 REQ /replication {file=segments_3&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication} status=0 QTime=0 
[junit4:junit4]   2> 20958 T371 C4 P46397 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 20961 T371 C4 P46397 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 20961 T371 C4 P46397 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=unloadcollection3
[junit4:junit4]   2> 20962 T371 C4 P46397 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f99c24 lockFactory=org.apache.lucene.store.NativeFSLockFactory@fd70bf),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f99c24 lockFactory=org.apache.lucene.store.NativeFSLockFactory@fd70bf),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 20963 T371 C4 P46397 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 20963 T371 C4 P46397 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 20963 T371 C4 P46397 oass.SolrIndexSearcher.<init> Opening Searcher@9f9de6 main
[junit4:junit4]   2> 20964 T370 oasc.SolrCore.registerSearcher [unloadcollection3] Registered new searcher Searcher@9f9de6 main{StandardDirectoryReader(segments_3:1:nrt)}
[junit4:junit4]   2> 20965 T371 C4 P46397 oasc.RecoveryStrategy.replay No replay needed. core=unloadcollection3
[junit4:junit4]   2> 20965 T371 C4 P46397 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=unloadcollection3
[junit4:junit4]   2> 20965 T371 C4 P46397 oasc.ZkController.publish publishing core=unloadcollection3 state=active
[junit4:junit4]   2> 20965 T371 C4 P46397 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 20966 T371 C4 P46397 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=unloadcollection3
[junit4:junit4]   2> 21448 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21449 T302 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46397/t_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:46397_t__unloadcollection3",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46397_t_",
[junit4:junit4]   2> 	  "core":"unloadcollection3"}
[junit4:junit4]   2> 21453 T361 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> 21453 T347 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> 21453 T333 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> 21453 T301 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> 21454 T319 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> 21454 T365 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> 21453 T306 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> 21940 T278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21941 T278 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: unloadcollection
[junit4:junit4]   2> 21941 T278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2>  C4_STATE=coll:unloadcollection core:unloadcollection3 props:{base_url=http://127.0.0.1:46397/t_, collection=unloadcollection, state=active, shard=shard1, node_name=127.0.0.1:46397_t_, core=unloadcollection3}
[junit4:junit4]   2> 21995 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[20 (1430184797455515648)]} 0 2
[junit4:junit4]   2>  C2_STATE=coll:unloadcollection core:unloadcollection2 props:{base_url=http://127.0.0.1:50484/t_, collection=unloadcollection, state=active, shard=shard1, node_name=127.0.0.1:50484_t_, core=unloadcollection2}
[junit4:junit4]   2> 21998 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[20 (1430184797455515648)]} 0 2
[junit4:junit4]   2> 21998 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[20 (1430184797455515648)]} 0 18
[junit4:junit4]   2> 21999 T342 C4 P46397 /update {version=2&wt=javabin} {add=[20]} 0 34
[junit4:junit4]   2> 22008 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[21 (1430184797479632896)]} 0 1
[junit4:junit4]   2> 22008 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[21 (1430184797479632896)]} 0 1
[junit4:junit4]   2> 22009 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[21 (1430184797479632896)]} 0 5
[junit4:junit4]   2> 22009 T340 C4 P46397 /update {version=2&wt=javabin} {add=[21]} 0 7
[junit4:junit4]   2> 22018 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[22 (1430184797490118656)]} 0 1
[junit4:junit4]   2> 22018 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[22 (1430184797490118656)]} 0 1
[junit4:junit4]   2> 22019 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[22 (1430184797490118656)]} 0 5
[junit4:junit4]   2> 22019 T341 C4 P46397 /update {version=2&wt=javabin} {add=[22]} 0 7
[junit4:junit4]   2> 22028 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[23 (1430184797500604416)]} 0 1
[junit4:junit4]   2> 22028 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[23 (1430184797500604416)]} 0 1
[junit4:junit4]   2> 22029 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[23 (1430184797500604416)]} 0 5
[junit4:junit4]   2> 22029 T343 C4 P46397 /update {version=2&wt=javabin} {add=[23]} 0 7
[junit4:junit4]   2> 22037 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[24 (1430184797511090176)]} 0 1
[junit4:junit4]   2> 22037 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[24 (1430184797511090176)]} 0 1
[junit4:junit4]   2> 22045 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[24 (1430184797511090176)]} 0 11
[junit4:junit4]   2> 22046 T342 C4 P46397 /update {version=2&wt=javabin} {add=[24]} 0 14
[junit4:junit4]   2> 22054 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[25 (1430184797528915968)]} 0 0
[junit4:junit4]   2> 22054 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[25 (1430184797528915968)]} 0 0
[junit4:junit4]   2> 22055 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[25 (1430184797528915968)]} 0 5
[junit4:junit4]   2> 22056 T340 C4 P46397 /update {version=2&wt=javabin} {add=[25]} 0 8
[junit4:junit4]   2> 22064 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[26 (1430184797538353152)]} 0 1
[junit4:junit4]   2> 22064 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[26 (1430184797538353152)]} 0 1
[junit4:junit4]   2> 22064 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[26 (1430184797538353152)]} 0 4
[junit4:junit4]   2> 22065 T341 C4 P46397 /update {version=2&wt=javabin} {add=[26]} 0 7
[junit4:junit4]   2> 22073 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[27 (1430184797547790336)]} 0 1
[junit4:junit4]   2> 22073 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[27 (1430184797547790336)]} 0 1
[junit4:junit4]   2> 22074 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[27 (1430184797547790336)]} 0 5
[junit4:junit4]   2> 22074 T343 C4 P46397 /update {version=2&wt=javabin} {add=[27]} 0 7
[junit4:junit4]   2> 22083 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[28 (1430184797558276096)]} 0 1
[junit4:junit4]   2> 22083 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[28 (1430184797558276096)]} 0 1
[junit4:junit4]   2> 22084 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[28 (1430184797558276096)]} 0 5
[junit4:junit4]   2> 22084 T342 C4 P46397 /update {version=2&wt=javabin} {add=[28]} 0 7
[junit4:junit4]   2> 22092 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[29 (1430184797568761856)]} 0 0
[junit4:junit4]   2> 22093 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[29 (1430184797568761856)]} 0 1
[junit4:junit4]   2> 22093 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[29 (1430184797568761856)]} 0 4
[junit4:junit4]   2> 22094 T340 C4 P46397 /update {version=2&wt=javabin} {add=[29]} 0 7
[junit4:junit4]   2> 22101 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[30 (1430184797579247616)]} 0 0
[junit4:junit4]   2> 22102 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[30 (1430184797579247616)]} 0 1
[junit4:junit4]   2> 22103 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[30 (1430184797579247616)]} 0 5
[junit4:junit4]   2> 22103 T341 C4 P46397 /update {version=2&wt=javabin} {add=[30]} 0 7
[junit4:junit4]   2> 22111 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[31 (1430184797588684800)]} 0 0
[junit4:junit4]   2> 22111 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[31 (1430184797588684800)]} 0 0
[junit4:junit4]   2> 22112 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[31 (1430184797588684800)]} 0 4
[junit4:junit4]   2> 22112 T343 C4 P46397 /update {version=2&wt=javabin} {add=[31]} 0 7
[junit4:junit4]   2> 22121 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[32 (1430184797598121984)]} 0 1
[junit4:junit4]   2> 22121 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[32 (1430184797598121984)]} 0 1
[junit4:junit4]   2> 22122 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[32 (1430184797598121984)]} 0 5
[junit4:junit4]   2> 22122 T342 C4 P46397 /update {version=2&wt=javabin} {add=[32]} 0 7
[junit4:junit4]   2> 22130 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[33 (1430184797608607744)]} 0 1
[junit4:junit4]   2> 22130 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[33 (1430184797608607744)]} 0 1
[junit4:junit4]   2> 22131 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[33 (1430184797608607744)]} 0 4
[junit4:junit4]   2> 22131 T340 C4 P46397 /update {version=2&wt=javabin} {add=[33]} 0 7
[junit4:junit4]   2> 22138 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[34 (1430184797616996352)]} 0 0
[junit4:junit4]   2> 22138 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[34 (1430184797616996352)]} 0 0
[junit4:junit4]   2> 22139 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[34 (1430184797616996352)]} 0 4
[junit4:junit4]   2> 22140 T341 C4 P46397 /update {version=2&wt=javabin} {add=[34]} 0 7
[junit4:junit4]   2> 22147 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[35 (1430184797627482112)]} 0 0
[junit4:junit4]   2> 22148 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[35 (1430184797627482112)]} 0 1
[junit4:junit4]   2> 22148 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[35 (1430184797627482112)]} 0 4
[junit4:junit4]   2> 22149 T343 C4 P46397 /update {version=2&wt=javabin} {add=[35]} 0 7
[junit4:junit4]   2> 22156 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[36 (1430184797635870720)]} 0 0
[junit4:junit4]   2> 22156 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[36 (1430184797635870720)]} 0 0
[junit4:junit4]   2> 22157 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[36 (1430184797635870720)]} 0 4
[junit4:junit4]   2> 22158 T342 C4 P46397 /update {version=2&wt=javabin} {add=[36]} 0 7
[junit4:junit4]   2> 22165 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[37 (1430184797645307904)]} 0 0
[junit4:junit4]   2> 22166 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[37 (1430184797645307904)]} 0 1
[junit4:junit4]   2> 22166 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[37 (1430184797645307904)]} 0 4
[junit4:junit4]   2> 22167 T340 C4 P46397 /update {version=2&wt=javabin} {add=[37]} 0 7
[junit4:junit4]   2> 22175 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[38 (1430184797655793664)]} 0 1
[junit4:junit4]   2> 22175 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[38 (1430184797655793664)]} 0 1
[junit4:junit4]   2> 22176 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[38 (1430184797655793664)]} 0 5
[junit4:junit4]   2> 22176 T341 C4 P46397 /update {version=2&wt=javabin} {add=[38]} 0 7
[junit4:junit4]   2> 22183 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[39 (1430184797665230848)]} 0 0
[junit4:junit4]   2> 22183 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[39 (1430184797665230848)]} 0 0
[junit4:junit4]   2> 22184 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[39 (1430184797665230848)]} 0 4
[junit4:junit4]   2> 22185 T343 C4 P46397 /update {version=2&wt=javabin} {add=[39]} 0 7
[junit4:junit4]   2> 22192 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[40 (1430184797673619456)]} 0 1
[junit4:junit4]   2> 22192 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[40 (1430184797673619456)]} 0 1
[junit4:junit4]   2> 22192 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[40 (1430184797673619456)]} 0 3
[junit4:junit4]   2> 22193 T342 C4 P46397 /update {version=2&wt=javabin} {add=[40]} 0 6
[junit4:junit4]   2> 22200 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[41 (1430184797682008064)]} 0 0
[junit4:junit4]   2> 22200 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[41 (1430184797682008064)]} 0 0
[junit4:junit4]   2> 22201 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[41 (1430184797682008064)]} 0 4
[junit4:junit4]   2> 22201 T340 C4 P46397 /update {version=2&wt=javabin} {add=[41]} 0 6
[junit4:junit4]   2> 22209 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[42 (1430184797691445248)]} 0 1
[junit4:junit4]   2> 22209 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[42 (1430184797691445248)]} 0 1
[junit4:junit4]   2> 22209 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[42 (1430184797691445248)]} 0 3
[junit4:junit4]   2> 22210 T341 C4 P46397 /update {version=2&wt=javabin} {add=[42]} 0 6
[junit4:junit4]   2> 22217 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[43 (1430184797699833856)]} 0 0
[junit4:junit4]   2> 22217 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[43 (1430184797699833856)]} 0 0
[junit4:junit4]   2> 22218 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[43 (1430184797699833856)]} 0 4
[junit4:junit4]   2> 22219 T343 C4 P46397 /update {version=2&wt=javabin} {add=[43]} 0 7
[junit4:junit4]   2> 22226 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[44 (1430184797709271040)]} 0 0
[junit4:junit4]   2> 22226 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[44 (1430184797709271040)]} 0 0
[junit4:junit4]   2> 22227 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[44 (1430184797709271040)]} 0 4
[junit4:junit4]   2> 22228 T342 C4 P46397 /update {version=2&wt=javabin} {add=[44]} 0 7
[junit4:junit4]   2> 22235 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[45 (1430184797718708224)]} 0 1
[junit4:junit4]   2> 22235 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[45 (1430184797718708224)]} 0 1
[junit4:junit4]   2> 22235 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[45 (1430184797718708224)]} 0 4
[junit4:junit4]   2> 22236 T340 C4 P46397 /update {version=2&wt=javabin} {add=[45]} 0 6
[junit4:junit4]   2> 22243 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[46 (1430184797727096832)]} 0 1
[junit4:junit4]   2> 22243 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[46 (1430184797727096832)]} 0 1
[junit4:junit4]   2> 22243 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[46 (1430184797727096832)]} 0 3
[junit4:junit4]   2> 22244 T341 C4 P46397 /update {version=2&wt=javabin} {add=[46]} 0 6
[junit4:junit4]   2> 22252 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[47 (1430184797735485440)]} 0 1
[junit4:junit4]   2> 22252 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[47 (1430184797735485440)]} 0 1
[junit4:junit4]   2> 22252 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[47 (1430184797735485440)]} 0 4
[junit4:junit4]   2> 22253 T343 C4 P46397 /update {version=2&wt=javabin} {add=[47]} 0 7
[junit4:junit4]   2> 22260 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[48 (1430184797744922624)]} 0 1
[junit4:junit4]   2> 22260 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[48 (1430184797744922624)]} 0 1
[junit4:junit4]   2> 22261 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[48 (1430184797744922624)]} 0 4
[junit4:junit4]   2> 22261 T342 C4 P46397 /update {version=2&wt=javabin} {add=[48]} 0 6
[junit4:junit4]   2> 22269 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[49 (1430184797754359808)]} 0 1
[junit4:junit4]   2> 22269 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[49 (1430184797754359808)]} 0 1
[junit4:junit4]   2> 22270 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[49 (1430184797754359808)]} 0 5
[junit4:junit4]   2> 22271 T340 C4 P46397 /update {version=2&wt=javabin} {add=[49]} 0 8
[junit4:junit4]   2> 22278 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[50 (1430184797763796992)]} 0 1
[junit4:junit4]   2> 22278 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[50 (1430184797763796992)]} 0 1
[junit4:junit4]   2> 22279 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[50 (1430184797763796992)]} 0 4
[junit4:junit4]   2> 22279 T342 C4 P46397 /update {version=2&wt=javabin} {add=[50]} 0 6
[junit4:junit4]   2> 22287 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[51 (1430184797773234176)]} 0 1
[junit4:junit4]   2> 22287 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[51 (1430184797773234176)]} 0 1
[junit4:junit4]   2> 22287 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[51 (1430184797773234176)]} 0 3
[junit4:junit4]   2> 22288 T340 C4 P46397 /update {version=2&wt=javabin} {add=[51]} 0 7
[junit4:junit4]   2> 22295 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[52 (1430184797781622784)]} 0 1
[junit4:junit4]   2> 22295 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[52 (1430184797781622784)]} 0 1
[junit4:junit4]   2> 22296 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[52 (1430184797781622784)]} 0 4
[junit4:junit4]   2> 22296 T343 C4 P46397 /update {version=2&wt=javabin} {add=[52]} 0 6
[junit4:junit4]   2> 22303 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[53 (1430184797791059968)]} 0 0
[junit4:junit4]   2> 22303 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[53 (1430184797791059968)]} 0 0
[junit4:junit4]   2> 22304 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[53 (1430184797791059968)]} 0 4
[junit4:junit4]   2> 22305 T341 C4 P46397 /update {version=2&wt=javabin} {add=[53]} 0 6
[junit4:junit4]   2> 22312 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[54 (1430184797799448576)]} 0 1
[junit4:junit4]   2> 22312 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[54 (1430184797799448576)]} 0 1
[junit4:junit4]   2> 22313 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[54 (1430184797799448576)]} 0 4
[junit4:junit4]   2> 22314 T342 C4 P46397 /update {version=2&wt=javabin} {add=[54]} 0 7
[junit4:junit4]   2> 22321 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[55 (1430184797808885760)]} 0 0
[junit4:junit4]   2> 22321 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[55 (1430184797808885760)]} 0 0
[junit4:junit4]   2> 22322 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[55 (1430184797808885760)]} 0 4
[junit4:junit4]   2> 22322 T340 C4 P46397 /update {version=2&wt=javabin} {add=[55]} 0 6
[junit4:junit4]   2> 22330 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[56 (1430184797818322944)]} 0 1
[junit4:junit4]   2> 22330 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[56 (1430184797818322944)]} 0 1
[junit4:junit4]   2> 22331 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[56 (1430184797818322944)]} 0 4
[junit4:junit4]   2> 22336 T343 C4 P46397 /update {version=2&wt=javabin} {add=[56]} 0 11
[junit4:junit4]   2> 22344 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[57 (1430184797833003008)]} 0 1
[junit4:junit4]   2> 22344 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[57 (1430184797833003008)]} 0 1
[junit4:junit4]   2> 22345 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[57 (1430184797833003008)]} 0 4
[junit4:junit4]   2> 22345 T341 C4 P46397 /update {version=2&wt=javabin} {add=[57]} 0 6
[junit4:junit4]   2> 22352 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[58 (1430184797842440192)]} 0 0
[junit4:junit4]   2> 22352 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[58 (1430184797842440192)]} 0 0
[junit4:junit4]   2> 22353 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[58 (1430184797842440192)]} 0 4
[junit4:junit4]   2> 22353 T343 C4 P46397 /update {version=2&wt=javabin} {add=[58]} 0 6
[junit4:junit4]   2> 22361 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[59 (1430184797850828800)]} 0 1
[junit4:junit4]   2> 22361 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[59 (1430184797850828800)]} 0 1
[junit4:junit4]   2> 22361 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[59 (1430184797850828800)]} 0 3
[junit4:junit4]   2> 22362 T340 C4 P46397 /update {version=2&wt=javabin} {add=[59]} 0 6
[junit4:junit4]   2> 22369 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[60 (1430184797859217408)]} 0 1
[junit4:junit4]   2> 22369 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[60 (1430184797859217408)]} 0 1
[junit4:junit4]   2> 22370 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[60 (1430184797859217408)]} 0 4
[junit4:junit4]   2> 22370 T342 C4 P46397 /update {version=2&wt=javabin} {add=[60]} 0 6
[junit4:junit4]   2> 22378 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[61 (1430184797868654592)]} 0 1
[junit4:junit4]   2> 22378 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[61 (1430184797868654592)]} 0 1
[junit4:junit4]   2> 22379 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[61 (1430184797868654592)]} 0 5
[junit4:junit4]   2> 22379 T341 C4 P46397 /update {version=2&wt=javabin} {add=[61]} 0 6
[junit4:junit4]   2> 22386 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[62 (1430184797877043200)]} 0 0
[junit4:junit4]   2> 22386 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[62 (1430184797877043200)]} 0 0
[junit4:junit4]   2> 22387 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[62 (1430184797877043200)]} 0 4
[junit4:junit4]   2> 22387 T343 C4 P46397 /update {version=2&wt=javabin} {add=[62]} 0 6
[junit4:junit4]   2> 22394 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[63 (1430184797886480384)]} 0 0
[junit4:junit4]   2> 22394 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[63 (1430184797886480384)]} 0 0
[junit4:junit4]   2> 22395 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[63 (1430184797886480384)]} 0 3
[junit4:junit4]   2> 22396 T340 C4 P46397 /update {version=2&wt=javabin} {add=[63]} 0 6
[junit4:junit4]   2> 22403 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[64 (1430184797894868992)]} 0 1
[junit4:junit4]   2> 22403 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[64 (1430184797894868992)]} 0 1
[junit4:junit4]   2> 22404 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[64 (1430184797894868992)]} 0 4
[junit4:junit4]   2> 22404 T342 C4 P46397 /update {version=2&wt=javabin} {add=[64]} 0 6
[junit4:junit4]   2> 22412 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[65 (1430184797904306176)]} 0 1
[junit4:junit4]   2> 22412 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[65 (1430184797904306176)]} 0 1
[junit4:junit4]   2> 22412 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[65 (1430184797904306176)]} 0 4
[junit4:junit4]   2> 22413 T341 C4 P46397 /update {version=2&wt=javabin} {add=[65]} 0 6
[junit4:junit4]   2> 22420 T327 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[66 (1430184797912694784)]} 0 1
[junit4:junit4]   2> 22420 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[66 (1430184797912694784)]} 0 1
[junit4:junit4]   2> 22420 T315 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[66 (1430184797912694784)]} 0 3
[junit4:junit4]   2> 22421 T343 C4 P46397 /update {version=2&wt=javabin} {add=[66]} 0 6
[junit4:junit4]   2> 22428 T341 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[67 (1430184797921083392)]} 0 1
[junit4:junit4]   2> 22428 T326 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[67 (1430184797921083392)]} 0 1
[junit4:junit4]   2> 22428 T314 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[67 (1430184797921083392)]} 0 3
[junit4:junit4]   2> 22429 T340 C4 P46397 /update {version=2&wt=javabin} {add=[67]} 0 6
[junit4:junit4]   2> 22435 T328 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[68 (1430184797929472000)]} 0 0
[junit4:junit4]   2> 22435 T343 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[68 (1430184797929472000)]} 0 0
[junit4:junit4]   2> 22436 T313 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[68 (1430184797929472000)]} 0 3
[junit4:junit4]   2> 22436 T342 C4 P46397 /update {version=2&wt=javabin} {add=[68]} 0 5
[junit4:junit4]   2> 22443 T329 C2 P50484 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[69 (1430184797937860608)]} 0 0
[junit4:junit4]   2> 22443 T340 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[69 (1430184797937860608)]} 0 0
[junit4:junit4]   2> 22444 T312 C3 P42736 /update {update.distrib=TOLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:46397/t_/unloadcollection3/} {add=[69 (1430184797937860608)]} 0 3
[junit4:junit4]   2> 22445 T341 C4 P46397 /update {version=2&wt=javabin} {add=[69]} 0 6
[junit4:junit4]   2> 22452 T342 C4 P46397 /update {update.distrib=FROMLEADER&version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/t_/unloadcollection1/} {add=[70 (1430184797946249216)]} 0 1
[junit4:junit4]   2> 22452 T327 C2 P50484 /update {update

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

t4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:36098/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([73CCE53B9CE60069:F22A6B23EBB96055]: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:197)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:487)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 35325 T1743 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 35329 T1742 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4]   2> 35368 T1817 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 35368 T1817 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 35368 T1817 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 35368 T1817 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 35647 T1746 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=de_GR, timezone=Asia/Qyzylorda
[junit4:junit4]   2> NOTE: Linux 3.2.0-39-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=2,free=40037992,total=116998144
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestLMJelinekMercerSimilarityFactory, DirectSolrSpellCheckerTest, RequestHandlersTest, TestStressLucene, StatsComponentTest, PingRequestHandlerTest, TestCSVResponseWriter, RequiredFieldsTest, TestSolr4Spatial, HardAutoCommitTest, XmlUpdateRequestHandlerTest, SampleTest, TestRecovery, CurrencyFieldXmlFileTest, MultiTermTest, DateMathParserTest, SignatureUpdateProcessorFactoryTest, LeaderElectionIntegrationTest, SuggesterTSTTest, TestPropInject, TestComponentsName, TestDynamicFieldCollectionResource, DisMaxRequestHandlerTest, TestOmitPositions, TestUtils, TimeZoneUtilsTest, SolrCoreCheckLockOnStartupTest, TestElisionMultitermQuery, TestRandomFaceting, TestSystemIdResolver, TestIndexingPerformance, TestQueryTypes, SolrPluginUtilsTest, SpellPossibilityIteratorTest, EchoParamsTest, TestDistributedGrouping, SolrCmdDistributorTest, TestStressReorder, TestMultiCoreConfBootstrap, TestFieldTypeCollectionResource, TestDFRSimilarityFactory, TermVectorComponentDistributedTest, SpellCheckComponentTest, TestArbitraryIndexDir, TestJmxIntegration, TestLuceneMatchVersion, FullSolrCloudDistribCmdsTest, TestAnalyzedSuggestions, TestDynamicFieldResource, NumericFieldsTest, URLClassifyProcessorTest, TestCSVLoader, DefaultValueUpdateProcessorTest, TestUpdate, JSONWriterTest, TestBinaryField, TestPostingsSolrHighlighter, HighlighterTest, DOMUtilTest, SpellCheckCollatorTest, TestFiltering, TestCodecSupport, ZkNodePropsTest, OpenExchangeRatesOrgProviderTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test]
[junit4:junit4] Completed on J1 in 35.85s, 1 test, 1 error <<< FAILURES!

[...truncated 484 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:375: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:355: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:183: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1210: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:874: There were test failures: 272 suites, 1146 tests, 1 error, 1 failure, 13 ignored (7 assumptions)

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



Mime
View raw message