You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/03/09 14:28:00 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b79) - Build #
4637 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/4637/
Java: 32bit/jdk1.8.0-ea-b79 -client -XX:+UseG1GC -XX:MarkStackSize=256K
2 tests failed.
REGRESSION: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch
Error Message:
No live SolrServers available to handle this request:[http://127.0.0.1:59076/collection1, http://127.0.0.1:43711/collection1, http://127.0.0.1:60608/collection1]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:59076/collection1, http://127.0.0.1:43711/collection1, http://127.0.0.1:60608/collection1]
at __randomizedtesting.SeedInfo.seed([B74BEFE38987AA35:36AD61FBFED8CA09]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:333)
at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:306)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.queryServer(AbstractFullDistribZkTestBase.java:1136)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:505)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:487)
at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSomeDocsAndRecover(BasicDistributedZk2Test.java:284)
at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:116)
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)
Caused by: org.apache.solr.common.SolrException: Server at http://127.0.0.1:60608/collection1 returned non ok status:503, message:Service Unavailable
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.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:264)
... 49 more
REGRESSION: org.apache.solr.cloud.UnloadDistributedZkTest.testDistribSearch
Error Message:
Still found shard
Stack Trace:
java.lang.AssertionError: Still found shard
at __randomizedtesting.SeedInfo.seed([B74BEFE38987AA35:36AD61FBFED8CA09]: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 8909 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4] 2> 0 T215 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4] 2> 6 T215 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362834687709
[junit4:junit4] 2> 10 T215 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 13 T216 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 39 T216 oaz.Environment.logEnv Server environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 39 T216 oaz.Environment.logEnv Server environment:host.name=serv1
[junit4:junit4] 2> 39 T216 oaz.Environment.logEnv Server environment:java.version=1.8.0-ea
[junit4:junit4] 2> 39 T216 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4] 2> 40 T216 oaz.Environment.logEnv Server environment:java.home=/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b79/jre
[junit4:junit4] 2> 40 T216 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/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> 42 T216 oaz.Environment.logEnv Server environment:java.library.path=/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit4:junit4] 2> 42 T216 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4] 2> 42 T216 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4] 2> 42 T216 oaz.Environment.logEnv Server environment:os.name=Linux
[junit4:junit4] 2> 43 T216 oaz.Environment.logEnv Server environment:os.arch=i386
[junit4:junit4] 2> 43 T216 oaz.Environment.logEnv Server environment:os.version=3.2.0-38-generic
[junit4:junit4] 2> 43 T216 oaz.Environment.logEnv Server environment:user.name=jenkins
[junit4:junit4] 2> 43 T216 oaz.Environment.logEnv Server environment:user.home=/var/lib/jenkins
[junit4:junit4] 2> 44 T216 oaz.Environment.logEnv Server environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 61 T216 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 62 T216 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 62 T216 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 64 T216 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 99 T216 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4] 2> 113 T215 oasc.ZkTestServer.run start zk server on port:37648
[junit4:junit4] 2> 140 T215 oaz.Environment.logEnv Client environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 141 T215 oaz.Environment.logEnv Client environment:host.name=serv1
[junit4:junit4] 2> 142 T215 oaz.Environment.logEnv Client environment:java.version=1.8.0-ea
[junit4:junit4] 2> 142 T215 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4] 2> 142 T215 oaz.Environment.logEnv Client environment:java.home=/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b79/jre
[junit4:junit4] 2> 143 T215 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/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> 145 T215 oaz.Environment.logEnv Client environment:java.library.path=/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit4:junit4] 2> 145 T215 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4] 2> 145 T215 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4] 2> 146 T215 oaz.Environment.logEnv Client environment:os.name=Linux
[junit4:junit4] 2> 146 T215 oaz.Environment.logEnv Client environment:os.arch=i386
[junit4:junit4] 2> 147 T215 oaz.Environment.logEnv Client environment:os.version=3.2.0-38-generic
[junit4:junit4] 2> 147 T215 oaz.Environment.logEnv Client environment:user.name=jenkins
[junit4:junit4] 2> 147 T215 oaz.Environment.logEnv Client environment:user.home=/var/lib/jenkins
[junit4:junit4] 2> 148 T215 oaz.Environment.logEnv Client environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 150 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5af3e5
[junit4:junit4] 2> 172 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 176 T221 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 176 T221 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 176 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59863
[junit4:junit4] 2> 185 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59863
[junit4:junit4] 2> 189 T219 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 200 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450000 with negotiated timeout 10000 for client /127.0.0.1:59863
[junit4:junit4] 2> 200 T221 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450000, negotiated timeout = 10000
[junit4:junit4] 2> 203 T222 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5af3e5 name:ZooKeeperConnection Watcher:127.0.0.1:37648 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 204 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 205 T215 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 229 T220 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d4f4707450000
[junit4:junit4] 2> 230 T222 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 230 T215 oaz.ZooKeeper.close Session: 0x13d4f4707450000 closed
[junit4:junit4] 2> 230 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1bb2124
[junit4:junit4] 2> 231 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 231 T223 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 232 T223 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 274 T217 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:59863 which had sessionid 0x13d4f4707450000
[junit4:junit4] 2> 274 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59864
[junit4:junit4] 2> 275 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59864
[junit4:junit4] 2> 276 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450001 with negotiated timeout 10000 for client /127.0.0.1:59864
[junit4:junit4] 2> 276 T223 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450001, negotiated timeout = 10000
[junit4:junit4] 2> 277 T224 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1bb2124 name:ZooKeeperConnection Watcher:127.0.0.1:37648/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 277 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 279 T215 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 284 T215 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 287 T215 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 289 T215 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 293 T215 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> 301 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 313 T215 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> 314 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 319 T215 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> 319 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 322 T215 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> 323 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 326 T215 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> 326 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 330 T215 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> 330 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 334 T215 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> 335 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 339 T215 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> 340 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 343 T215 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> 344 T215 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 347 T220 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d4f4707450001
[junit4:junit4] 2> 348 T217 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:59864 which had sessionid 0x13d4f4707450001
[junit4:junit4] 2> 349 T224 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 349 T215 oaz.ZooKeeper.close Session: 0x13d4f4707450001 closed
[junit4:junit4] 2> 453 T215 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 456 T215 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59400
[junit4:junit4] 2> 456 T215 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 456 T215 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 457 T215 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051
[junit4:junit4] 2> 457 T215 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051/solr.xml
[junit4:junit4] 2> 457 T215 oasc.CoreContainer.<init> New CoreContainer 21060327
[junit4:junit4] 2> 458 T215 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051/'
[junit4:junit4] 2> 458 T215 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051/'
[junit4:junit4] 2> 479 T215 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 480 T215 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 480 T215 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 480 T215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 481 T215 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 481 T215 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 481 T215 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 482 T215 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 482 T215 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 482 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 491 T215 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 504 T215 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:37648/solr
[junit4:junit4] 2> 512 T215 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 513 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1be7ccf
[junit4:junit4] 2> 514 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 514 T234 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 515 T234 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 515 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59865
[junit4:junit4] 2> 515 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59865
[junit4:junit4] 2> 517 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450002 with negotiated timeout 20000 for client /127.0.0.1:59865
[junit4:junit4] 2> 517 T234 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450002, negotiated timeout = 20000
[junit4:junit4] 2> 517 T235 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1be7ccf name:ZooKeeperConnection Watcher:127.0.0.1:37648 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 518 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 519 T220 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d4f4707450002
[junit4:junit4] 2> 520 T217 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:59865 which had sessionid 0x13d4f4707450002
[junit4:junit4] 2> 520 T235 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 520 T215 oaz.ZooKeeper.close Session: 0x13d4f4707450002 closed
[junit4:junit4] 2> 521 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 525 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1e3142c
[junit4:junit4] 2> 526 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 526 T236 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 527 T236 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 527 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59866
[junit4:junit4] 2> 527 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59866
[junit4:junit4] 2> 528 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450003 with negotiated timeout 20000 for client /127.0.0.1:59866
[junit4:junit4] 2> 528 T236 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450003, negotiated timeout = 20000
[junit4:junit4] 2> 529 T237 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e3142c name:ZooKeeperConnection Watcher:127.0.0.1:37648/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 529 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 536 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 541 T215 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 543 T215 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59400_
[junit4:junit4] 2> 545 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:59400_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:59400_
[junit4:junit4] 2> 546 T215 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59400_
[junit4:junit4] 2> 549 T215 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 558 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:delete cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4] 2> 559 T215 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 564 T215 oasc.Overseer.start Overseer (id=89314734100512771-127.0.0.1:59400_-n_0000000000) starting
[junit4:junit4] 2> 565 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 570 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 571 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 575 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 576 T239 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4] 2> 577 T215 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 579 T215 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4:junit4] 2> 581 T215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 589 T238 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 593 T240 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051/collection1
[junit4:junit4] 2> 593 T240 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 594 T240 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 594 T240 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 595 T240 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051/collection1/'
[junit4:junit4] 2> 596 T240 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051/collection1/lib/README' to classloader
[junit4:junit4] 2> 596 T240 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 633 T240 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 684 T240 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 685 T240 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 691 T240 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 1164 T240 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 1170 T240 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 1173 T240 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 1187 T240 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1190 T240 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1195 T240 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1196 T240 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 1197 T240 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 1197 T240 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1198 T240 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 1199 T240 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 1199 T240 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362834688051/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/control/data/
[junit4:junit4] 2> 1199 T240 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dd61db
[junit4:junit4] 2> 1200 T240 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 1201 T240 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/control/data forceNew: false
[junit4:junit4] 2> 1201 T240 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/control/data/index/
[junit4:junit4] 2> 1202 T240 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/control/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 1202 T240 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/control/data/index forceNew: false
[junit4:junit4] 2> 1204 T240 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@68fde1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1feb934),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 1204 T240 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 1207 T240 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 1207 T240 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 1208 T240 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 1208 T240 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 1209 T240 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 1209 T240 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 1210 T240 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 1210 T240 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 1211 T240 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 1214 T240 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 1218 T240 oass.SolrIndexSearcher.<init> Opening Searcher@b1db5 main
[junit4:junit4] 2> 1219 T240 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/control/data/tlog
[junit4:junit4] 2> 1219 T240 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 1220 T240 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 1224 T241 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b1db5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 1227 T240 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 1227 T240 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 1228 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x55 zxid:0x2a txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4] 2> 2093 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 2094 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:59400",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "core_node_name":"1",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:59400_"}
[junit4:junit4] 2> 2094 T238 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 2108 T238 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 2109 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x5d zxid:0x2d txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4] 2> 2120 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 2231 T240 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 2232 T240 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59400 collection:control_collection shard:shard1
[junit4:junit4] 2> 2236 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4] 2> 2244 T240 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 2254 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:delete cxid:0x77 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4] 2> 2255 T240 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 2255 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x78 zxid:0x38 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 2257 T240 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 2257 T240 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 2258 T240 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59400/collection1/
[junit4:junit4] 2> 2258 T240 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 2258 T240 oasc.SyncStrategy.syncToMe http://127.0.0.1:59400/collection1/ has no replicas
[junit4:junit4] 2> 2258 T240 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59400/collection1/
[junit4:junit4] 2> 2259 T240 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 2263 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450003 type:create cxid:0x82 zxid:0x3c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3624 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3690 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 3723 T240 oasc.ZkController.register We are http://127.0.0.1:59400/collection1/ and leader is http://127.0.0.1:59400/collection1/
[junit4:junit4] 2> 3723 T240 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59400
[junit4:junit4] 2> 3724 T240 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 3724 T240 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 3724 T240 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 3726 T240 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3727 T215 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 3727 T215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 3728 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 3731 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 3733 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ef0382
[junit4:junit4] 2> 3734 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3734 T243 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3735 T243 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 3735 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59868
[junit4:junit4] 2> 3735 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59868
[junit4:junit4] 2> 3736 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450004 with negotiated timeout 10000 for client /127.0.0.1:59868
[junit4:junit4] 2> 3736 T243 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450004, negotiated timeout = 10000
[junit4:junit4] 2> 3737 T244 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ef0382 name:ZooKeeperConnection Watcher:127.0.0.1:37648/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3737 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3739 T215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 3742 T215 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 3810 T215 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 3812 T215 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59076
[junit4:junit4] 2> 3813 T215 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 3813 T215 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 3814 T215 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444
[junit4:junit4] 2> 3814 T215 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/solr.xml
[junit4:junit4] 2> 3815 T215 oasc.CoreContainer.<init> New CoreContainer 2354667
[junit4:junit4] 2> 3815 T215 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/'
[junit4:junit4] 2> 3816 T215 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/'
[junit4:junit4] 2> 3840 T215 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 3840 T215 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 3841 T215 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 3841 T215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 3842 T215 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 3842 T215 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 3842 T215 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 3843 T215 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 3843 T215 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 3843 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 3852 T215 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 3865 T215 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:37648/solr
[junit4:junit4] 2> 3866 T215 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 3866 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c5da1a
[junit4:junit4] 2> 3867 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3867 T254 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3868 T254 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 3868 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59869
[junit4:junit4] 2> 3869 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59869
[junit4:junit4] 2> 3870 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450005 with negotiated timeout 20000 for client /127.0.0.1:59869
[junit4:junit4] 2> 3870 T254 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450005, negotiated timeout = 20000
[junit4:junit4] 2> 3870 T255 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c5da1a name:ZooKeeperConnection Watcher:127.0.0.1:37648 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3870 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3872 T220 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d4f4707450005
[junit4:junit4] 2> 3872 T255 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 3872 T217 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:59869 which had sessionid 0x13d4f4707450005
[junit4:junit4] 2> 3872 T215 oaz.ZooKeeper.close Session: 0x13d4f4707450005 closed
[junit4:junit4] 2> 3873 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 3876 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@24247b
[junit4:junit4] 2> 3877 T256 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3877 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3878 T256 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 3878 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59870
[junit4:junit4] 2> 3878 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59870
[junit4:junit4] 2> 3879 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450006 with negotiated timeout 20000 for client /127.0.0.1:59870
[junit4:junit4] 2> 3879 T256 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450006, negotiated timeout = 20000
[junit4:junit4] 2> 3880 T257 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@24247b name:ZooKeeperConnection Watcher:127.0.0.1:37648/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3880 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3881 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:create cxid:0x1 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3882 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:create cxid:0x2 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3885 T215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 4890 T215 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59076_
[junit4:junit4] 2> 4891 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:delete cxid:0xb zxid:0x4c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:59076_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:59076_
[junit4:junit4] 2> 4891 T215 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59076_
[junit4:junit4] 2> 4894 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 4895 T257 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 4895 T244 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 4896 T237 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 4902 T258 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/collection1
[junit4:junit4] 2> 4903 T258 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 4903 T258 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 4903 T258 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 4905 T258 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/collection1/'
[junit4:junit4] 2> 4905 T258 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/collection1/lib/README' to classloader
[junit4:junit4] 2> 4905 T258 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 4948 T258 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 4985 T258 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 4986 T258 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 4991 T258 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 5204 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 5205 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:59400",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "core_node_name":"1",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:59400_"}
[junit4:junit4] 2> 5210 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5210 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5211 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5464 T258 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 5472 T258 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 5475 T258 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 5488 T258 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 5491 T258 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 5495 T258 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 5496 T258 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 5496 T258 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 5496 T258 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 5497 T258 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 5497 T258 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 5498 T258 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty1/
[junit4:junit4] 2> 5498 T258 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dd61db
[junit4:junit4] 2> 5499 T258 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 5500 T258 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty1 forceNew: false
[junit4:junit4] 2> 5500 T258 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty1/index/
[junit4:junit4] 2> 5500 T258 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 5500 T258 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty1/index forceNew: false
[junit4:junit4] 2> 5502 T258 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d9e8e8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1078ed4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 5502 T258 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 5504 T258 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 5504 T258 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 5505 T258 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 5505 T258 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 5506 T258 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 5506 T258 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 5507 T258 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 5507 T258 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 5508 T258 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 5510 T258 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 5513 T258 oass.SolrIndexSearcher.<init> Opening Searcher@6e8b79 main
[junit4:junit4] 2> 5513 T258 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty1/tlog
[junit4:junit4] 2> 5514 T258 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 5514 T258 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 5521 T259 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e8b79 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 5524 T258 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 5524 T258 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 6716 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6717 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:59076",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "core_node_name":"2",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:59076_"}
[junit4:junit4] 2> 6717 T238 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4] 2> 6717 T238 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4] 2> 6722 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 6722 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 6722 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 7526 T258 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 7526 T258 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59076 collection:collection1 shard:shard2
[junit4:junit4] 2> 7527 T258 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4] 2> 7534 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:delete cxid:0x48 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4] 2> 7534 T258 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 7535 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:create cxid:0x49 zxid:0x5d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 7536 T258 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 7536 T258 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 7537 T258 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59076/collection1/
[junit4:junit4] 2> 7537 T258 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 7537 T258 oasc.SyncStrategy.syncToMe http://127.0.0.1:59076/collection1/ has no replicas
[junit4:junit4] 2> 7537 T258 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59076/collection1/
[junit4:junit4] 2> 7537 T258 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4] 2> 7542 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:create cxid:0x53 zxid:0x61 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8227 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8234 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 8234 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 8234 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 8247 T258 oasc.ZkController.register We are http://127.0.0.1:59076/collection1/ and leader is http://127.0.0.1:59076/collection1/
[junit4:junit4] 2> 8247 T258 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59076
[junit4:junit4] 2> 8248 T258 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 8248 T258 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 8248 T258 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 8250 T258 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8251 T215 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 8252 T215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 8252 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 8343 T215 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 8347 T215 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:43711
[junit4:junit4] 2> 8348 T215 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 8349 T215 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 8349 T215 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960
[junit4:junit4] 2> 8350 T215 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960/solr.xml
[junit4:junit4] 2> 8350 T215 oasc.CoreContainer.<init> New CoreContainer 11975568
[junit4:junit4] 2> 8351 T215 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960/'
[junit4:junit4] 2> 8352 T215 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960/'
[junit4:junit4] 2> 8380 T215 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 8381 T215 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 8381 T215 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 8382 T215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 8382 T215 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 8383 T215 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 8383 T215 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 8384 T215 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 8384 T215 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 8385 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 8396 T215 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 8423 T215 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:37648/solr
[junit4:junit4] 2> 8424 T215 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 8424 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@12cb970
[junit4:junit4] 2> 8425 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 8425 T270 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 8426 T270 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 8426 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59873
[junit4:junit4] 2> 8427 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59873
[junit4:junit4] 2> 8428 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450007 with negotiated timeout 20000 for client /127.0.0.1:59873
[junit4:junit4] 2> 8428 T270 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450007, negotiated timeout = 20000
[junit4:junit4] 2> 8428 T271 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12cb970 name:ZooKeeperConnection Watcher:127.0.0.1:37648 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 8428 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 8430 T220 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d4f4707450007
[junit4:junit4] 2> 8430 T271 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 8431 T217 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:59873 which had sessionid 0x13d4f4707450007
[junit4:junit4] 2> 8430 T215 oaz.ZooKeeper.close Session: 0x13d4f4707450007 closed
[junit4:junit4] 2> 8433 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 8438 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@11b5584
[junit4:junit4] 2> 8440 T272 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 8439 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 8440 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59874
[junit4:junit4] 2> 8440 T272 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 8441 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59874
[junit4:junit4] 2> 8442 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450008 with negotiated timeout 20000 for client /127.0.0.1:59874
[junit4:junit4] 2> 8442 T272 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450008, negotiated timeout = 20000
[junit4:junit4] 2> 8442 T273 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11b5584 name:ZooKeeperConnection Watcher:127.0.0.1:37648/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 8442 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 8444 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450008 type:create cxid:0x1 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8445 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450008 type:create cxid:0x2 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8446 T215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 9449 T215 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43711_
[junit4:junit4] 2> 9450 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450008 type:delete cxid:0xb zxid:0x70 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:43711_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:43711_
[junit4:junit4] 2> 9451 T215 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43711_
[junit4:junit4] 2> 9452 T237 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> 9453 T244 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> 9453 T257 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 9453 T273 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 9453 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 9455 T237 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 9456 T244 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 9460 T274 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960/collection1
[junit4:junit4] 2> 9460 T274 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 9461 T274 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 9461 T274 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 9462 T274 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960/collection1/'
[junit4:junit4] 2> 9462 T274 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960/collection1/lib/README' to classloader
[junit4:junit4] 2> 9463 T274 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 9491 T274 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 9535 T274 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 9536 T274 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 9542 T274 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 9742 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9743 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:59076",
[junit4:junit4] 2> "shard":"shard2",
[junit4:junit4] 2> "core_node_name":"2",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:59076_"}
[junit4:junit4] 2> 9747 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 9747 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 9747 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 9747 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10046 T274 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 10052 T274 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 10055 T274 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 10074 T274 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 10077 T274 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 10080 T274 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 10081 T274 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 10082 T274 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 10082 T274 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 10083 T274 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 10083 T274 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 10083 T274 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362834695960/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty2/
[junit4:junit4] 2> 10083 T274 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dd61db
[junit4:junit4] 2> 10084 T274 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 10084 T274 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty2 forceNew: false
[junit4:junit4] 2> 10084 T274 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty2/index/
[junit4:junit4] 2> 10085 T274 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 10085 T274 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty2/index forceNew: false
[junit4:junit4] 2> 10086 T274 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e30c63 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e7e24f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 10087 T274 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 10088 T274 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 10089 T274 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 10089 T274 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 10090 T274 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 10090 T274 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 10090 T274 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 10091 T274 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 10091 T274 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 10091 T274 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 10094 T274 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 10096 T274 oass.SolrIndexSearcher.<init> Opening Searcher@1b99ea main
[junit4:junit4] 2> 10097 T274 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty2/tlog
[junit4:junit4] 2> 10097 T274 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 10097 T274 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 10102 T275 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b99ea main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 10104 T274 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 10105 T274 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 11253 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 11253 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:43711",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "core_node_name":"3",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:43711_"}
[junit4:junit4] 2> 11254 T238 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4] 2> 11254 T238 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 11262 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 11262 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 11262 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 11262 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12107 T274 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 12107 T274 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:43711 collection:collection1 shard:shard1
[junit4:junit4] 2> 12108 T274 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 12114 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450008 type:delete cxid:0x47 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4] 2> 12115 T274 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 12115 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450008 type:create cxid:0x48 zxid:0x80 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 12117 T274 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 12117 T274 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 12117 T274 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:43711/collection1/
[junit4:junit4] 2> 12118 T274 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 12118 T274 oasc.SyncStrategy.syncToMe http://127.0.0.1:43711/collection1/ has no replicas
[junit4:junit4] 2> 12118 T274 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:43711/collection1/
[junit4:junit4] 2> 12119 T274 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 12128 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450008 type:create cxid:0x51 zxid:0x83 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 12767 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 12774 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12774 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12774 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12774 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12782 T274 oasc.ZkController.register We are http://127.0.0.1:43711/collection1/ and leader is http://127.0.0.1:43711/collection1/
[junit4:junit4] 2> 12782 T274 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:43711
[junit4:junit4] 2> 12782 T274 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 12782 T274 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 12783 T274 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 12787 T274 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 12789 T215 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 12789 T215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 12790 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 12864 T215 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 12866 T215 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60608
[junit4:junit4] 2> 12867 T215 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 12867 T215 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 12868 T215 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495
[junit4:junit4] 2> 12868 T215 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495/solr.xml
[junit4:junit4] 2> 12868 T215 oasc.CoreContainer.<init> New CoreContainer 29026145
[junit4:junit4] 2> 12869 T215 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495/'
[junit4:junit4] 2> 12869 T215 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495/'
[junit4:junit4] 2> 12890 T215 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 12890 T215 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 12891 T215 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 12891 T215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 12891 T215 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 12892 T215 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 12892 T215 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 12892 T215 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 12893 T215 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 12893 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 12902 T215 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 12915 T215 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:37648/solr
[junit4:junit4] 2> 12916 T215 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 12916 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1661054
[junit4:junit4] 2> 12917 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 12917 T286 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 12918 T286 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 12918 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59883
[junit4:junit4] 2> 12919 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59883
[junit4:junit4] 2> 12919 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f4707450009 with negotiated timeout 20000 for client /127.0.0.1:59883
[junit4:junit4] 2> 12919 T286 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f4707450009, negotiated timeout = 20000
[junit4:junit4] 2> 12920 T287 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1661054 name:ZooKeeperConnection Watcher:127.0.0.1:37648 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 12920 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 12921 T220 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d4f4707450009
[junit4:junit4] 2> 12922 T287 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 12922 T217 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:59883 which had sessionid 0x13d4f4707450009
[junit4:junit4] 2> 12922 T215 oaz.ZooKeeper.close Session: 0x13d4f4707450009 closed
[junit4:junit4] 2> 12922 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 12925 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@a8b651
[junit4:junit4] 2> 12926 T288 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 12926 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 12926 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59884
[junit4:junit4] 2> 12927 T288 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 12927 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59884
[junit4:junit4] 2> 12928 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f470745000a with negotiated timeout 20000 for client /127.0.0.1:59884
[junit4:junit4] 2> 12928 T288 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f470745000a, negotiated timeout = 20000
[junit4:junit4] 2> 12928 T289 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a8b651 name:ZooKeeperConnection Watcher:127.0.0.1:37648/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 12929 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 12929 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f470745000a type:create cxid:0x1 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 12930 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f470745000a type:create cxid:0x2 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 12932 T215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 13934 T215 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60608_
[junit4:junit4] 2> 13935 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f470745000a type:delete cxid:0xb zxid:0x92 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60608_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60608_
[junit4:junit4] 2> 13936 T215 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60608_
[junit4:junit4] 2> 13938 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13938 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13939 T244 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 13939 T257 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 13939 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 13939 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 13940 T237 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 13940 T289 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 13941 T273 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 13946 T290 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495/collection1
[junit4:junit4] 2> 13946 T290 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 13947 T290 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 13947 T290 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 13948 T290 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495/collection1/'
[junit4:junit4] 2> 13948 T290 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495/collection1/lib/README' to classloader
[junit4:junit4] 2> 13949 T290 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 13983 T290 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 14022 T290 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 14023 T290 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 14027 T290 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 14280 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 14281 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:43711",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "core_node_name":"3",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:43711_"}
[junit4:junit4] 2> 14284 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 14284 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 14284 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 14284 T289 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 14284 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 14507 T290 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 14513 T290 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 14516 T290 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 14527 T290 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 14530 T290 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 14533 T290 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 14534 T290 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 14535 T290 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 14535 T290 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 14536 T290 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 14536 T290 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 14536 T290 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362834700495/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty3/
[junit4:junit4] 2> 14536 T290 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dd61db
[junit4:junit4] 2> 14537 T290 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 14537 T290 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty3 forceNew: false
[junit4:junit4] 2> 14538 T290 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty3/index/
[junit4:junit4] 2> 14538 T290 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 14538 T290 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty3/index forceNew: false
[junit4:junit4] 2> 14540 T290 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@84c526 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1859e1f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 14540 T290 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 14542 T290 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 14542 T290 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 14542 T290 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 14543 T290 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 14543 T290 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 14543 T290 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 14544 T290 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 14544 T290 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 14545 T290 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 14547 T290 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 14549 T290 oass.SolrIndexSearcher.<init> Opening Searcher@1294200 main
[junit4:junit4] 2> 14550 T290 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty3/tlog
[junit4:junit4] 2> 14550 T290 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 14550 T290 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 14556 T291 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1294200 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 14558 T290 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 14558 T290 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 15788 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 15789 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:60608",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "core_node_name":"4",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:60608_"}
[junit4:junit4] 2> 15790 T238 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4] 2> 15790 T238 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4] 2> 15794 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 15794 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 15794 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 15794 T289 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 15794 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 16559 T290 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 16560 T290 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60608 collection:collection1 shard:shard2
[junit4:junit4] 2> 16563 T290 oasc.ZkController.register We are http://127.0.0.1:60608/collection1/ and leader is http://127.0.0.1:59076/collection1/
[junit4:junit4] 2> 16563 T290 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60608
[junit4:junit4] 2> 16563 T290 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 16563 T290 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C8 name=collection1 org.apache.solr.core.SolrCore@24a394 url=http://127.0.0.1:60608/collection1 node=127.0.0.1:60608_ C8_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:60608, core=collection1, state=down, collection=collection1, node_name=127.0.0.1:60608_}
[junit4:junit4] 2> 16567 T292 C8 P60608 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 16567 T290 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 16568 T292 C8 P60608 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 16568 T292 C8 P60608 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 16568 T292 C8 P60608 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 16568 T215 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 16569 T215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 16569 T292 C8 P60608 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 16569 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 16575 T215 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 16576 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 16580 T251 oasc.CoreContainer.create Creating SolrCore 'onenodecollectioncore' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/onenodecollectioncore
[junit4:junit4] 2> 16581 T251 oasc.ZkController.createCollectionZkNode Check for collection zkNode:onenodecollection
[junit4:junit4] 2> 16582 T251 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:onenodecollection
[junit4:junit4] 2> 16582 T251 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4] 2> 16583 T251 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4] 2> 16584 T251 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection
[junit4:junit4] 2> 16586 T251 oasc.ZkController.readConfigName Load collection config from:/collections/onenodecollection
[junit4:junit4] 2> 16587 T251 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/onenodecollectioncore/'
[junit4:junit4] 2> 16626 T251 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 16663 T251 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 16664 T251 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 16668 T251 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 17107 T251 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 17113 T251 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 17116 T251 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 17128 T251 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 17131 T251 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 17134 T251 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 17135 T251 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 17136 T251 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 17136 T251 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 17137 T251 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 17137 T251 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 17137 T251 oasc.SolrCore.<init> [onenodecollectioncore] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362834691444/onenodecollectioncore/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362834687709/onenodecollection/
[junit4:junit4] 2> 17138 T251 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dd61db
[junit4:junit4] 2> 17139 T251 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 17139 T251 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362834687709/onenodecollection forceNew: false
[junit4:junit4] 2> 17140 T251 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362834687709/onenodecollection/index/
[junit4:junit4] 2> 17140 T251 oasc.SolrCore.initIndex WARNING [onenodecollectioncore] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362834687709/onenodecollection/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 17140 T251 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362834687709/onenodecollection/index forceNew: false
[junit4:junit4] 2> 17142 T251 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c112c8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@156b319),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 17142 T251 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 17144 T251 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 17144 T251 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 17145 T251 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 17145 T251 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 17146 T251 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 17146 T251 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 17147 T251 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 17147 T251 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 17148 T251 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 17150 T251 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 17153 T251 oass.SolrIndexSearcher.<init> Opening Searcher@142ea44 main
[junit4:junit4] 2> 17153 T251 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362834687709/onenodecollection/tlog
[junit4:junit4] 2> 17154 T251 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 17154 T251 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 17158 T294 oasc.SolrCore.registerSearcher [onenodecollectioncore] Registered new searcher Searcher@142ea44 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 17159 T251 oasc.ZkController.publish publishing core=onenodecollectioncore state=down
[junit4:junit4] 2> 17300 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 17300 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:60608",
[junit4:junit4] 2> "shard":"shard2",
[junit4:junit4] 2> "core_node_name":"4",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"recovering",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:60608_"}
[junit4:junit4] 2> 17304 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "roles":"none",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:59076",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "core":"onenodecollectioncore",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "collection":"onenodecollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:59076_"}
[junit4:junit4] 2> 17304 T238 oasc.Overseer$ClusterStateUpdater.createCollection Create collection onenodecollection with numShards 1
[junit4:junit4] 2> 17304 T238 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 17314 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 17314 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 17314 T289 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 17314 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 17314 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 17580 T250 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {wt=javabin&checkLive=true&core=collection1&state=recovering&nodeName=127.0.0.1:60608_&version=2&onlyIfLeader=true&action=PREPRECOVERY&coreNodeName=4} status=0 QTime=1002
[junit4:junit4] 2> 18161 T251 oasc.CoreContainer.registerCore registering core: onenodecollectioncore
[junit4:junit4] 2> 18161 T251 oasc.ZkController.register Register replica - core:onenodecollectioncore address:http://127.0.0.1:59076 collection:onenodecollection shard:shard1
[junit4:junit4] 2> 18162 T251 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leader_elect/shard1/election
[junit4:junit4] 2> 18168 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:delete cxid:0xa1 zxid:0xad txntype:-1 reqpath:n/a Error Path:/solr/collections/onenodecollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/onenodecollection/leaders
[junit4:junit4] 2> 18169 T251 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 18169 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:create cxid:0xa2 zxid:0xae txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 18171 T251 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 18171 T251 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 18172 T251 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59076/onenodecollectioncore/
[junit4:junit4] 2> 18172 T251 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 18173 T251 oasc.SyncStrategy.syncToMe http://127.0.0.1:59076/onenodecollectioncore/ has no replicas
[junit4:junit4] 2> 18173 T251 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59076/onenodecollectioncore/
[junit4:junit4] 2> 18173 T251 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leaders/shard1
[junit4:junit4] 2> 18177 T220 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d4f4707450006 type:create cxid:0xac zxid:0xb2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 18820 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 18827 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 18827 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 18828 T289 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 18828 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 18828 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 18830 T251 oasc.ZkController.register We are http://127.0.0.1:59076/onenodecollectioncore/ and leader is http://127.0.0.1:59076/onenodecollectioncore/
[junit4:junit4] 2> 18831 T251 oasc.ZkController.register No LogReplay needed for core=onenodecollectioncore baseURL=http://127.0.0.1:59076
[junit4:junit4] 2> 18831 T251 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 18832 T251 oasc.ZkController.publish publishing core=onenodecollectioncore state=active
[junit4:junit4] 2> 18833 T251 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 18834 T251 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {roles=none&wt=javabin&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362834687709/onenodecollection&numShards=1&version=2&collection=onenodecollection&action=CREATE&name=onenodecollectioncore} status=0 QTime=2254
[junit4:junit4] 2> 18835 T215 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: onenodecollection failOnTimeout:true timeout (sec):230
[junit4:junit4] 2> 18836 T215 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> C8_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:60608, shard=shard2, core=collection1, state=recovering, collection=collection1, node_name=127.0.0.1:60608_}
[junit4:junit4] 2> 19581 T292 C8 P60608 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:59076/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4] 2> 19584 T292 C8 P60608 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4] 2> 19589 T292 C8 P60608 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60608 START replicas=[http://127.0.0.1:59076/collection1/] nUpdates=100
[junit4:junit4] 2> 19591 T292 C8 P60608 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4] 2> 19591 T292 C8 P60608 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4] 2> 19591 T292 C8 P60608 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4] 2> 19591 T292 C8 P60608 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4] 2> 19592 T292 C8 P60608 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4] 2> 19592 T292 C8 P60608 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:59076/collection1/. core=collection1
[junit4:junit4] 2> 19592 T292 C8 P60608 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C9 name=collection1 org.apache.solr.core.SolrCore@b64156 url=http://127.0.0.1:59076/collection1 node=127.0.0.1:59076_ C9_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:59076, shard=shard2, core=collection1, state=active, collection=collection1, node_name=127.0.0.1:59076_, leader=true}
[junit4:junit4] 2> 19599 T252 C9 P59076 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 19600 T251 C9 P59076 REQ /get {wt=javabin&getVersions=100&qt=/get&version=2&distrib=false} status=0 QTime=1
[junit4:junit4] 2> 19601 T252 C9 P59076 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d9e8e8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1078ed4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 19602 T252 C9 P59076 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 19602 T252 C9 P59076 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d9e8e8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1078ed4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d9e8e8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1078ed4),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 19603 T252 C9 P59076 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4] 2> 19603 T252 C9 P59076 oass.SolrIndexSearcher.<init> Opening Searcher@1f656a4 realtime
[junit4:junit4] 2> 19603 T252 C9 P59076 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 19604 T252 C9 P59076 /update {waitSearcher=true&wt=javabin&version=2&openSearcher=false&commit=true&softCommit=false&commit_end_point=true} {commit=} 0 5
[junit4:junit4] 2> 19623 T292 C8 P60608 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 19623 T292 C8 P60608 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4] 2> 19627 T292 C8 P60608 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 19629 T250 C9 P59076 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 19630 T250 C9 P59076 REQ /replication {wt=javabin&command=indexversion&qt=/replication&version=2} status=0 QTime=1
[junit4:junit4] 2> 19630 T292 C8 P60608 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4] 2> 19630 T292 C8 P60608 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 19631 T292 C8 P60608 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 19633 T251 C9 P59076 REQ /replication {wt=javabin&generation=2&command=filelist&qt=/replication&version=2} status=0 QTime=1
[junit4:junit4] 2> 19634 T292 C8 P60608 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4] 2> 19635 T292 C8 P60608 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362834687709/jetty3/index.20130310031147336 forceNew: false
[junit4:junit4] 2> 19635 T292 C8 P60608 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@d2c619 lockFactory=org.apache.lucene.store.NativeFSLockFactory@cc577a) fullCopy=false
[junit4:junit4] 2> 19640 T252 C9 P59076 REQ /replication {wt=filestream&generation=2&command=filecontent&file=segments_2&qt=/replication&checksum=true} status=0 QTime=1
[junit4:junit4] 2> 19645 T292 C8 P60608 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 19646 T292 C8 P60608 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 19646 T292 C8 P60608 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 19647 T292 C8 P60608 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@84c526 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1859e1f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@84c526 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1859e1f),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 19648 T292 C8 P60608 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4] 2> 19648 T292 C8 P60608 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 19648 T292 C8 P60608 oass.SolrIndexSearcher.<init> Opening Searcher@1f514ed main
[junit4:junit4] 2> 19649 T291 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f514ed main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4] 2> 19650 T292 C8 P60608 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@d2c619 lockFactory=org.apache.lucene.store.NativeFSLockFactory@cc577a)
[junit4:junit4] 2> 19650 T292 C8 P60608 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4] 2> 19651 T292 C8 P60608 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4] 2> 19651 T292 C8 P60608 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 19651 T292 C8 P60608 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 19652 T292 C8 P60608 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4] 2> 19838 T215 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 20333 T238 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 20334 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "roles":"none",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:59076",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "core":"onenodecollectioncore",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "collection":"onenodecollection",
[junit4:junit4] 2> "node_name":"127.0.0.1:59076_"}
[junit4:junit4] 2> 20337 T238 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "base_url":"http://127.0.0.1:60608",
[junit4:junit4] 2> "shard":"shard2",
[junit4:junit4] 2> "core_node_name":"4",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:60608_"}
[junit4:junit4] 2> 20341 T257 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 20341 T289 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 20341 T237 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 20341 T273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 20341 T244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4] 2> 20840 T215 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 20841 T215 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: onenodecollection
[junit4:junit4] 2> 20842 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C10 name=onenodecollectioncore org.apache.solr.core.SolrCore@bd9b20 url=http://127.0.0.1:59076/onenodecollectioncore node=127.0.0.1:59076_ C10_STATE=coll:onenodecollection core:onenodecollectioncore props:{roles=none, base_url=http://127.0.0.1:59076, shard=shard1, core=onenodecollectioncore, state=active, collection=onenodecollection, node_name=127.0.0.1:59076_, leader=true}
[junit4:junit4] 2> 20860 T250 C10 P59076 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c112c8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@156b319),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 20860 T250 C10 P59076 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 20864 T250 C10 P59076 /update {wt=javabin&version=2} {add=[1 (1429035767364059136)]} 0 6
[junit4:junit4] 2> 20885 T251 C10 P59076 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 20891 T251 C10 P59076 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c112c8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@156b319),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c112c8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@156b319),segFN=segments_2,generation=2,filenames=[_0_Lucene41_0.pos, _0_Lucene42_0.dvd, _0_Lucene42_0.dvm, _0.si, _0.nvm, _0.fdt, _0_Lucene41_0.doc, _0.fdx, _0_Lucene41_0.tim, _0.fnm, segments_2, _0_Lucene41_0.tip, _0.nvd]
[junit4:junit4] 2> 20891 T251 C10 P59076 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0_Lucene41_0.pos, _0_Lucene42_0.dvd, _0_Lucene42_0.dvm, _0.si, _0.nvm, _0.fdt, _0_Lucene41_0.doc, _0.fdx, _0_Lucene41_0.tim, _0.fnm, segments_2, _0_Lucene41_0.tip, _0.nvd]
[junit4:junit4] 2> 20892 T251 C10 P59076 oass.SolrIndexSearcher.<init> Opening Searcher@154e6af main
[junit4:junit4] 2> 20893 T251 C10 P59076 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 20894 T294 oasc.SolrCore.registerSearcher [onenodecollectioncore] Registered new searcher Searcher@154e6af main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4:junit4] 2> 20895 T251 C10 P59076 /update {waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 10
[junit4:junit4] 2> 20899 T252 C10 P59076 REQ /select {q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 2> 20900 T215 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 20905 T253 C10 P59076 REQ /select {q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 2> ASYNC NEW_CORE C11 name=collection1 org.apache.solr.core.SolrCore@116d71b url=http://127.0.0.1:59400/collection1 node=127.0.0.1:59400_ C11_STATE=coll:control_collection core:collection1 props:{base_url=http://127.0.0.1:59400, shard=shard1, core=collection1, state=active, collection=control_collection, node_name=127.0.0.1:59400_, leader=true}
[junit4:junit4] 2> 20914 T233 C11 P59400 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@68fde1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1feb934),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 20915 T233 C11 P59400 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 20919 T233 C11 P59400 /update {wt=javabin&version=2} {add=[1 (1429035767420682240)]} 0 7
[junit4:junit4] 2> ASYNC NEW_CORE C12 name=collection1 org.apache.solr.core.SolrCore@f8971e url=http://127.0.0.1:43711/collection1 node=127.0.0.1:43711_ C12_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:43711, shard=shard1, core=collection1, state=active, collection=collection1, node_name=127.0.0.1:43711_, leader=true}
[junit4:junit4] 2> 20933 T267 C12 P43711 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e30c63 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e7e24f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 20934 T267 C12 P43711 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 20938 T267 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[1 (1429035767440605184)]} 0 7
[junit4:junit4] 2> 20938 T251 C9 P59076 /update {wt=javabin&version=2} {add=[1]} 0 13
[junit4:junit4] 2> 20941 T232 C11 P59400 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 20945 T232 C11 P59400 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@68fde1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1feb934),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@68fde1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1feb934),segFN=segments_2,generation=2,filenames=[_0_Lucene41_0.pos, _0_Lucene42_0.dvd, _0_Lucene42_0.dvm, _0.si, _0.nvm, _0.fdt, _0_Lucene41_0.doc, _0.fdx, _0_Lucene41_0.tim, _0.fnm, segments_2, _0_Lucene41_0.tip, _0.nvd]
[junit4:junit4] 2> 20945 T232 C11 P59400 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0_Lucene41_0.pos, _0_Lucene42_0.dvd, _0_Lucene42_0.dvm, _0.si, _0.nvm, _0.fdt, _0_Lucene41_0.doc, _0.fdx, _0_Lucene41_0.tim, _0.fnm, segments_2, _0_Lucene41_0.tip, _0.nvd]
[junit4:junit4] 2> 20947 T232 C11 P59400 oass.SolrIndexSearcher.<init> Opening Searcher@77229c main
[junit4:junit4] 2> 20948 T232 C11 P59400 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 20949 T241 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@77229c main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4:junit4] 2> 20949 T232 C11 P59400 /update {waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 8
[junit4:junit4] 2> 20951 T266 C12 P43711 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 20961 T266 C12 P43711 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e30c63 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e7e24f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e30c63 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e7e24f),segFN=segments_2,generation=2,filenames=[_0_Lucene41_0.pos, _0_Lucene42_0.dvd, _0_Lucene42_0.dvm, _0.si, _0.nvm, _0.fdt, _0_Lucene41_0.doc, _0.fdx, _0_Lucene41_0.tim, _0.fnm, segments_2, _0_Lucene41_0.tip, _0.nvd]
[junit4:junit4] 2> 20962 T266 C12 P43711 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0_Lucene41_0.pos, _0_Lucene42_0.dvd, _0_Lucene42_0.dvm, _0.si, _0.nvm, _0.fdt, _0_Lucene41_0.doc, _0.fdx, _0_Lucene41_0.tim, _0.fnm, segments_2, _0_Lucene41_0.tip, _0.nvd]
[junit4:junit4] 2> 20964 T266 C12 P43711 oass.SolrIndexSearcher.<init> Opening Searcher@1dd6d12 main
[junit4:junit4] 2> 20965 T266 C12 P43711 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 20966 T275 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dd6d12 main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4:junit4] 2> 20966 T266 C12 P43711 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:59076/collection1/, StdNode: http://127.0.0.1:60608/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4] 2> 20968 T253 C9 P59076 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 20969 T253 C9 P59076 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d9e8e8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1078ed4),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d9e8e8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1078ed4),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4] 2> 20970 T253 C9 P59076 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4] 2> 20970 T253 C9 P59076 oass.SolrIndexSearcher.<init> Opening Searcher@a56257 main
[junit4:junit4] 2> 20971 T253 C9 P59076 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 20972 T259 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a56257 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4] 2> 20972 T253 C9 P59076 /update {waitSearcher=true&wt=javabin&version=2&expungeDeletes=false&commit=true&softCommit=false&commit_end_point=true} {commit=} 0 4
[junit4:junit4] 2> C8_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:60608, shard=shard2, core=collection1, state=active, collection=collection1, node_name=127.0.0.1:60608_}
[junit4:junit4] 2> 20972 T285 C8 P60608 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 20973 T285 C8 P60608 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@84c526 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1859e1f),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@84c526 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1859e1f),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4] 2> 20974 T285 C8 P60608 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4] 2> 20974 T285 C8 P60608 oass.SolrIndexSearcher.<init> Opening Searcher@145f224 main
[junit4:junit4] 2> 20975 T285 C8 P60608 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 20975 T291 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@145f224 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4] 2> 20976 T285 C8 P60608 /update {waitSearcher=true&wt=javabin&version=2&expungeDeletes=false&commit=true&softCommit=false&commit_end_point=true} {commit=} 0 4
[junit4:junit4] 2> 20976 T266 C12 P43711 /update {waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 25
[junit4:junit4] 2> 20977 T215 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):230
[junit4:junit4] 2> 20978 T215 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 20979 T215 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: collection1
[junit4:junit4] 2> 20981 T231 C11 P59400 REQ /select {q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 2> 20983 T215 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37648/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c8ba39
[junit4:junit4] 2> 20983 T215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 20984 T301 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37648. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 20984 T301 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37648, initiating session
[junit4:junit4] 2> 20984 T217 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:59906
[junit4:junit4] 2> 20985 T217 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:59906
[junit4:junit4] 2> 20985 T219 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d4f470745000b with negotiated timeout 10000 for client /127.0.0.1:59906
[junit4:junit4] 2> 20986 T301 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37648, sessionid = 0x13d4f470745000b, negotiated timeout = 10000
[junit4:junit4] 2> 20986 T302 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c8ba39 name:ZooKeeperConnection Watcher:127.0.0.1:37648/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 20986 T215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 20988 T215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 20989 T220 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d4f470745000b
[junit4:junit4] 2> 20990 T302 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 20990 T215 oaz.ZooKeeper.close Session: 0x13d4f470745000b closed
[junit4:junit4] 2> 20990 T217 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:59906 which had sessionid 0x13d4f470745000b
[junit4:junit4] 2> 20992 T250 C9 P59076 REQ /select {q=*:*&wt=javabin&version=2&distrib=false} hits=0 status=0 QTime=1
[junit4:junit4] 2> 20993 T267 C12 P43711 REQ /select {q=*:*&wt=javabin&version=2&distrib=false} hits=1 status=0 QTime=0
[junit4:junit4] 2> 21011 T251 C9 P59076 REQ /select {q=*:*&wt=javabin&NOW=1362834708698&isShard=true&fsv=true&start=0&version=2&shard.url=127.0.0.1:59076/collection1/|127.0.0.1:60608/collection1/&fl=id,score&distrib=false&rows=10} hits=0 status=0 QTime=12
[junit4:junit4] 2> 21011 T268 C12 P43711 REQ /select {q=*:*&wt=javabin&NOW=1362834708698&isShard=true&fsv=true&start=0&version=2&shard.url=127.0.0.1:43711/collection1/&fl=id,score&distrib=false&rows=10} hits=1 status=0 QTime=12
[junit4:junit4] 2> 21020 T266 C12 P43711 REQ /select {ids=1&q=*:*&wt=javabin&NOW=1362834708698&isShard=true&version=2&shard.url=127.0.0.1:43711/collection1/&distrib=false} status=0 QTime=0
[junit4:junit4] 2> 21020 T269 C12 P43711 REQ /select {q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=24
[junit4:junit4] 2> 21027 T230 C11 P59400 /update {wt=javabin&version=2} {add=[2 (1429035767536025600)]} 0 4
[junit4:junit4] 2> 21037 T284 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[2 (1429035767544414208)]} 0 2
[junit4:junit4] 2> 21037 T252 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[2 (1429035767544414208)]} 0 6
[junit4:junit4] 2> 21038 T267 C12 P43711 /update {wt=javabin&version=2} {add=[2]} 0 9
[junit4:junit4] 2> 21043 T233 C11 P59400 /update {wt=javabin&version=2} {add=[3 (1429035767554899968)]} 0 3
[junit4:junit4] 2> 21054 T282 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[3 (1429035767561191424)]} 0 2
[junit4:junit4] 2> 21055 T253 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[3 (1429035767561191424)]} 0 8
[junit4:junit4] 2> 21056 T268 C12 P43711 /update {wt=javabin&version=2} {add=[3]} 0 11
[junit4:junit4] 2> 21060 T232 C11 P59400 /update {wt=javabin&version=2} {add=[4 (1429035767572725760)]} 0 2
[junit4:junit4] 2> 21067 T266 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[4 (1429035767579017216)]} 0 3
[junit4:junit4] 2> 21067 T250 C9 P59076 /update {wt=javabin&version=2} {add=[4]} 0 5
[junit4:junit4] 2> 21071 T231 C11 P59400 /update {wt=javabin&version=2} {add=[5 (1429035767585308672)]} 0 2
[junit4:junit4] 2> 21080 T283 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[5 (1429035767590551552)]} 0 2
[junit4:junit4] 2> 21080 T251 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[5 (1429035767590551552)]} 0 5
[junit4:junit4] 2> 21081 T269 C12 P43711 /update {wt=javabin&version=2} {add=[5]} 0 8
[junit4:junit4] 2> 21084 T230 C11 P59400 /update {wt=javabin&version=2} {add=[6 (1429035767598940160)]} 0 1
[junit4:junit4] 2> 21091 T285 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[6 (1429035767603134464)]} 0 2
[junit4:junit4] 2> 21092 T252 C9 P59076 /update {wt=javabin&version=2} {add=[6 (1429035767603134464)]} 0 6
[junit4:junit4] 2> 21096 T233 C11 P59400 /update {wt=javabin&version=2} {add=[7 (1429035767610474496)]} 0 2
[junit4:junit4] 2> 21102 T284 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[7 (1429035767614668800)]} 0 2
[junit4:junit4] 2> 21102 T253 C9 P59076 /update {wt=javabin&version=2} {add=[7 (1429035767614668800)]} 0 4
[junit4:junit4] 2> 21106 T232 C11 P59400 /update {wt=javabin&version=2} {add=[8 (1429035767622008832)]} 0 2
[junit4:junit4] 2> 21109 T267 C12 P43711 /update {wt=javabin&version=2} {add=[8 (1429035767625154560)]} 0 1
[junit4:junit4] 2> 21113 T231 C11 P59400 /update {wt=javabin&version=2} {add=[9 (1429035767629348864)]} 0 1
[junit4:junit4] 2> 21123 T282 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[9 (1429035767635640320)]} 0 2
[junit4:junit4] 2> 21124 T250 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[9 (1429035767635640320)]} 0 6
[junit4:junit4] 2> 21125 T268 C12 P43711 /update {wt=javabin&version=2} {add=[9]} 0 9
[junit4:junit4] 2> 21128 T230 C11 P59400 /update {wt=javabin&version=2} {add=[10 (1429035767645077504)]} 0 1
[junit4:junit4] 2> 21133 T266 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[10 (1429035767650320384)]} 0 1
[junit4:junit4] 2> 21134 T251 C9 P59076 /update {wt=javabin&version=2} {add=[10]} 0 3
[junit4:junit4] 2> 21137 T233 C11 P59400 /update {wt=javabin&version=2} {add=[11 (1429035767654514688)]} 0 1
[junit4:junit4] 2> 21143 T269 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[11 (1429035767659757568)]} 0 2
[junit4:junit4] 2> 21143 T252 C9 P59076 /update {wt=javabin&version=2} {add=[11]} 0 4
[junit4:junit4] 2> 21147 T232 C11 P59400 /update {wt=javabin&version=2} {add=[12 (1429035767665000448)]} 0 1
[junit4:junit4] 2> 21151 T267 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[12 (1429035767669194752)]} 0 1
[junit4:junit4] 2> 21152 T253 C9 P59076 /update {wt=javabin&version=2} {add=[12]} 0 3
[junit4:junit4] 2> 21156 T231 C11 P59400 /update {wt=javabin&version=2} {add=[13 (1429035767673389056)]} 0 2
[junit4:junit4] 2> 21161 T268 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[13 (1429035767679680512)]} 0 1
[junit4:junit4] 2> 21162 T250 C9 P59076 /update {wt=javabin&version=2} {add=[13]} 0 4
[junit4:junit4] 2> 21165 T230 C11 P59400 /update {wt=javabin&version=2} {add=[14 (1429035767683874816)]} 0 1
[junit4:junit4] 2> 21170 T269 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[14 (1429035767689117696)]} 0 1
[junit4:junit4] 2> 21170 T251 C9 P59076 /update {wt=javabin&version=2} {add=[14]} 0 3
[junit4:junit4] 2> 21174 T233 C11 P59400 /update {wt=javabin&version=2} {add=[15 (1429035767693312000)]} 0 1
[junit4:junit4] 2> 21179 T267 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[15 (1429035767698554880)]} 0 1
[junit4:junit4] 2> 21179 T252 C9 P59076 /update {wt=javabin&version=2} {add=[15]} 0 3
[junit4:junit4] 2> 21183 T232 C11 P59400 /update {wt=javabin&version=2} {add=[16 (1429035767702749184)]} 0 1
[junit4:junit4] 2> 21187 T268 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[16 (1429035767707992064)]} 0 0
[junit4:junit4] 2> 21188 T253 C9 P59076 /update {wt=javabin&version=2} {add=[16]} 0 3
[junit4:junit4] 2> 21193 T231 C11 P59400 /update {wt=javabin&version=2} {add=[17 (1429035767712186368)]} 0 3
[junit4:junit4] 2> 21204 T283 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[17 (1429035767717429248)]} 0 4
[junit4:junit4] 2> 21205 T250 C9 P59076 /update {wt=javabin&version=2} {add=[17 (1429035767717429248)]} 0 10
[junit4:junit4] 2> 21208 T230 C11 P59400 /update {wt=javabin&version=2} {add=[100 (1429035767730012160)]} 0 0
[junit4:junit4] 2> 21211 T266 C12 P43711 /update {wt=javabin&version=2} {add=[100 (1429035767733157888)]} 0 1
[junit4:junit4] 2> 21214 T233 C11 P59400 /update {wt=javabin&version=2} {add=[101 (1429035767736303616)]} 0 1
[junit4:junit4] 2> 21217 T267 C12 P43711 /update {wt=javabin&version=2} {add=[101 (1429035767739449344)]} 0 0
[junit4:junit4] 2> 21220 T232 C11 P59400 /update {wt=javabin&version=2} {add=[102 (1429035767741546496)]} 0 1
[junit4:junit4] 2> 21223 T268 C12 P43711 /update {wt=javabin&version=2} {add=[102 (1429035767744692224)]} 0 1
[junit4:junit4] 2> 21226 T231 C11 P59400 /update {wt=javabin&version=2} {add=[103 (1429035767747837952)]} 0 1
[junit4:junit4] 2> 21234 T285 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[103 (1429035767753080832)]} 0 1
[junit4:junit4] 2> 21235 T251 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[103 (1429035767753080832)]} 0 5
[junit4:junit4] 2> 21236 T269 C12 P43711 /update {wt=javabin&version=2} {add=[103]} 0 8
[junit4:junit4] 2> 21239 T230 C11 P59400 /update {wt=javabin&version=2} {add=[104 (1429035767761469440)]} 0 1
[junit4:junit4] 2> 21246 T284 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[104 (1429035767766712320)]} 0 1
[junit4:junit4] 2> 21246 T253 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[104 (1429035767766712320)]} 0 3
[junit4:junit4] 2> 21247 T266 C12 P43711 /update {wt=javabin&version=2} {add=[104]} 0 6
[junit4:junit4] 2> 21250 T233 C11 P59400 /update {wt=javabin&version=2} {add=[105 (1429035767773003776)]} 0 1
[junit4:junit4] 2> 21258 T282 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[105 (1429035767778246656)]} 0 1
[junit4:junit4] 2> 21259 T250 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[105 (1429035767778246656)]} 0 5
[junit4:junit4] 2> 21259 T267 C12 P43711 /update {wt=javabin&version=2} {add=[105]} 0 7
[junit4:junit4] 2> 21263 T232 C11 P59400 /update {wt=javabin&version=2} {add=[106 (1429035767786635264)]} 0 1
[junit4:junit4] 2> 21269 T283 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[106 (1429035767789780992)]} 0 1
[junit4:junit4] 2> 21270 T252 C9 P59076 /update {wt=javabin&version=2} {add=[106 (1429035767789780992)]} 0 5
[junit4:junit4] 2> 21274 T231 C11 P59400 /update {wt=javabin&version=2} {add=[107 (1429035767798169600)]} 0 1
[junit4:junit4] 2> 21279 T268 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[107 (1429035767803412480)]} 0 1
[junit4:junit4] 2> 21279 T251 C9 P59076 /update {wt=javabin&version=2} {add=[107]} 0 3
[junit4:junit4] 2> 21282 T230 C11 P59400 /update {wt=javabin&version=2} {add=[108 (1429035767806558208)]} 0 1
[junit4:junit4] 2> 21285 T269 C12 P43711 /update {wt=javabin&version=2} {add=[108 (1429035767809703936)]} 0 1
[junit4:junit4] 2> 21288 T233 C11 P59400 /update {wt=javabin&version=2} {add=[109 (1429035767812849664)]} 0 1
[junit4:junit4] 2> 21293 T266 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[109 (1429035767818092544)]} 0 1
[junit4:junit4] 2> 21293 T253 C9 P59076 /update {wt=javabin&version=2} {add=[109]} 0 3
[junit4:junit4] 2> 21297 T231 C11 P59400 /update {wt=javabin&version=2} {add=[110 (1429035767822286848)]} 0 2
[junit4:junit4] 2> 21301 T267 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[110 (1429035767827529728)]} 0 1
[junit4:junit4] 2> 21302 T250 C9 P59076 /update {wt=javabin&version=2} {add=[110]} 0 3
[junit4:junit4] 2> 21305 T230 C11 P59400 /update {wt=javabin&version=2} {add=[111 (1429035767830675456)]} 0 1
[junit4:junit4] 2> 21308 T268 C12 P43711 /update {wt=javabin&version=2} {add=[111 (1429035767834869760)]} 0 0
[junit4:junit4] 2> 21311 T232 C11 P59400 /update {wt=javabin&version=2} {add=[112 (1429035767838015488)]} 0 0
[junit4:junit4] 2> 21317 T285 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[112 (1429035767841161216)]} 0 1
[junit4:junit4] 2> 21318 T252 C9 P59076 /update {wt=javabin&version=2} {add=[112 (1429035767841161216)]} 0 5
[junit4:junit4] 2> 21321 T233 C11 P59400 /update {wt=javabin&version=2} {add=[113 (1429035767847452672)]} 0 1
[junit4:junit4] 2> 21326 T269 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[113 (1429035767852695552)]} 0 1
[junit4:junit4] 2> 21326 T251 C9 P59076 /update {wt=javabin&version=2} {add=[113]} 0 3
[junit4:junit4] 2> 21329 T231 C11 P59400 /update {wt=javabin&version=2} {add=[114 (1429035767856889856)]} 0 0
[junit4:junit4] 2> 21334 T266 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[114 (1429035767861084160)]} 0 1
[junit4:junit4] 2> 21334 T250 C9 P59076 /update {wt=javabin&version=2} {add=[114]} 0 3
[junit4:junit4] 2> 21338 T230 C11 P59400 /update {wt=javabin&version=2} {add=[115 (1429035767865278464)]} 0 1
[junit4:junit4] 2> 21343 T282 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[115 (1429035767868424192)]} 0 1
[junit4:junit4] 2> 21344 T252 C9 P59076 /update {wt=javabin&version=2} {add=[115 (1429035767868424192)]} 0 4
[junit4:junit4] 2> 21347 T232 C11 P59400 /update {wt=javabin&version=2} {add=[116 (1429035767874715648)]} 0 1
[junit4:junit4] 2> 21354 T283 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[116 (1429035767879958528)]} 0 1
[junit4:junit4] 2> 21355 T251 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[116 (1429035767879958528)]} 0 4
[junit4:junit4] 2> 21355 T267 C12 P43711 /update {wt=javabin&version=2} {add=[116]} 0 6
[junit4:junit4] 2> 21358 T233 C11 P59400 /update {wt=javabin&version=2} {add=[117 (1429035767886249984)]} 0 1
[junit4:junit4] 2> 21365 T284 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[117 (1429035767891492864)]} 0 1
[junit4:junit4] 2> 21366 T253 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[117 (1429035767891492864)]} 0 4
[junit4:junit4] 2> 21366 T268 C12 P43711 /update {wt=javabin&version=2} {add=[117]} 0 6
[junit4:junit4] 2> 21369 T231 C11 P59400 /update {wt=javabin&version=2} {add=[118 (1429035767897784320)]} 0 1
[junit4:junit4] 2> 21374 T269 C12 P43711 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=TOLEADER} {add=[118 (1429035767903027200)]} 0 1
[junit4:junit4] 2> 21375 T250 C9 P59076 /update {wt=javabin&version=2} {add=[118]} 0 4
[junit4:junit4] 2> 21379 T230 C11 P59400 /update {wt=javabin&version=2} {add=[119 (1429035767908270080)]} 0 1
[junit4:junit4] 2> 21385 T282 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[119 (1429035767911415808)]} 0 1
[junit4:junit4] 2> 21386 T252 C9 P59076 /update {wt=javabin&version=2} {add=[119 (1429035767911415808)]} 0 5
[junit4:junit4] 2> 21390 T232 C11 P59400 /update {wt=javabin&version=2} {add=[120 (1429035767919804416)]} 0 2
[junit4:junit4] 2> 21398 T283 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[120 (1429035767925047296)]} 0 1
[junit4:junit4] 2> 21398 T251 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[120 (1429035767925047296)]} 0 4
[junit4:junit4] 2> 21399 T266 C12 P43711 /update {wt=javabin&version=2} {add=[120]} 0 7
[junit4:junit4] 2> 21403 T233 C11 P59400 /update {wt=javabin&version=2} {add=[121 (1429035767933435904)]} 0 1
[junit4:junit4] 2> 21410 T284 C8 P60608 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:59076/collection1/&update.distrib=FROMLEADER} {add=[121 (1429035767938678784)]} 0 1
[junit4:junit4] 2> 21411 T253 C9 P59076 /update {wt=javabin&version=2&distrib.from=http://127.0.0.1:43711/collection1/&update.distrib=TOLEADER} {add=[121 (1429035767938678784)]} 0 4
[junit4:junit4] 2> 21411 T267 C12 P43711 /update {wt=javabin&version=2} {add=[121]} 0 6
[junit4:j
[...truncated too long message...]
ore":"test_unload_shard_and_collection_1",
[junit4:junit4] 1> "node_name":"127.0.0.1:33123__d%2Fwu",
[junit4:junit4] 1> "base_url":"http://127.0.0.1:33123/_d/wu"}
[junit4:junit4] 1> /solr/collections/control_collection (1)
[junit4:junit4] 1> DATA:
[junit4:junit4] 1> {"configName":"conf1"}
[junit4:junit4] 1> /solr/collections/control_collection/shards (0)
[junit4:junit4] 1> /zookeeper (1)
[junit4:junit4] 1> DATA:
[junit4:junit4] 1>
[junit4:junit4] 1>
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=UnloadDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=B74BEFE38987AA35 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ms -Dtests.timezone=Africa/Maputo -Dtests.file.encoding=UTF-8
[junit4:junit4] FAILURE 136s J0 | UnloadDistributedZkTest.testDistribSearch <<<
[junit4:junit4] > Throwable #1: java.lang.AssertionError: Still found shard
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([B74BEFE38987AA35:36AD61FBFED8CA09]:0)
[junit4:junit4] > at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4] > at org.apache.solr.cloud.UnloadDistributedZkTest.testUnloadShardAndCollection(UnloadDistributedZkTest.java:124)
[junit4:junit4] > at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistributedZkTest.java:77)
[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> 136347 T445 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> 136356 T444 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4] 2> 137422 T527 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 137422 T527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 137423 T527 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 137423 T527 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> NOTE: test params are: codec=Lucene42: {text=Lucene41(blocksize=128), multiDefault=MockFixedIntBlock(blockSize=1508), timestamp=Pulsing41(freqCutoff=10 minBlockSize=43 maxBlockSize=130), intDefault=Pulsing41(freqCutoff=10 minBlockSize=43 maxBlockSize=130), _version_=Pulsing41(freqCutoff=10 minBlockSize=43 maxBlockSize=130), range_facet_si=MockFixedIntBlock(blockSize=1508), a_t=Pulsing41(freqCutoff=10 minBlockSize=43 maxBlockSize=130), id=MockVariableIntBlock(baseBlockSize=24), range_facet_sl=Lucene41(blocksize=128), other_tl1=Pulsing41(freqCutoff=10 minBlockSize=43 maxBlockSize=130), range_facet_l=Pulsing41(freqCutoff=10 minBlockSize=43 maxBlockSize=130), a_si=MockFixedIntBlock(blockSize=1508)}, docValues:{timestamp=DocValuesFormat(name=Lucene42)}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=ms, timezone=Africa/Maputo
[junit4:junit4] 2> NOTE: Linux 3.2.0-38-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=253106032,total=411041792
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestSolrDeletionPolicy2, SynonymTokenizerTest, TestDocSet, DocValuesTest, MoreLikeThisHandlerTest, TestRemoteStreaming, LukeRequestHandlerTest, UUIDFieldTest, TestFieldResource, ShowFileRequestHandlerTest, TestWriterPerf, SuggesterFSTTest, RegexBoostProcessorTest, SolrCoreTest, PeerSyncTest, DirectSolrConnectionTest, DateFieldTest, SyncSliceTest, TestXIncludeConfig, TestSolrQueryParser, TestSolrCoreProperties, TestGroupingSearch, FieldMutatingUpdateProcessorTest, TestTrie, TestBadConfig, LoggingHandlerTest, TermsComponentTest, TestFastLRUCache, PolyFieldTest, UnloadDistributedZkTest]
[junit4:junit4] Completed on J0 in 137.64s, 1 test, 1 failure <<< FAILURES!
[...truncated 646 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:361: 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:1213: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:877: There were test failures: 269 suites, 1137 tests, 1 error, 1 failure, 13 ignored (7 assumptions)
Total time: 49 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b79 -client -XX:+UseG1GC -XX:MarkStackSize=256K
Email was triggered for: Failure
Sending email for trigger: Failure