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/17 08:53:24 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b79) - Build #
4743 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/4743/
Java: 32bit/jdk1.8.0-ea-b79 -client -XX:+UseG1GC -XX:MarkStackSize=256K
1 tests failed.
REGRESSION: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch
Error Message:
Server at http://127.0.0.1:35794/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:35794/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
at __randomizedtesting.SeedInfo.seed([897655F530CDF33A:890DBED47929306]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:487)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:722)
Build Log:
[...truncated 8890 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4] 2> 0 T15 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4] 2> 6 T15 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-1363505854839
[junit4:junit4] 2> 9 T15 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 12 T16 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 36 T16 oaz.Environment.logEnv Server environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 36 T16 oaz.Environment.logEnv Server environment:host.name=serv1
[junit4:junit4] 2> 36 T16 oaz.Environment.logEnv Server environment:java.version=1.8.0-ea
[junit4:junit4] 2> 37 T16 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4] 2> 37 T16 oaz.Environment.logEnv Server environment:java.home=/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b79/jre
[junit4:junit4] 2> 37 T16 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> 39 T16 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> 40 T16 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4] 2> 40 T16 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4] 2> 41 T16 oaz.Environment.logEnv Server environment:os.name=Linux
[junit4:junit4] 2> 41 T16 oaz.Environment.logEnv Server environment:os.arch=i386
[junit4:junit4] 2> 41 T16 oaz.Environment.logEnv Server environment:os.version=3.2.0-38-generic
[junit4:junit4] 2> 42 T16 oaz.Environment.logEnv Server environment:user.name=jenkins
[junit4:junit4] 2> 42 T16 oaz.Environment.logEnv Server environment:user.home=/var/lib/jenkins
[junit4:junit4] 2> 42 T16 oaz.Environment.logEnv Server environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 58 T16 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 59 T16 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 59 T16 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 64 T16 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 85 T16 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-1363505854839/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4] 2> 112 T15 oasc.ZkTestServer.run start zk server on port:36228
[junit4:junit4] 2> 135 T15 oaz.Environment.logEnv Client environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 136 T15 oaz.Environment.logEnv Client environment:host.name=serv1
[junit4:junit4] 2> 137 T15 oaz.Environment.logEnv Client environment:java.version=1.8.0-ea
[junit4:junit4] 2> 137 T15 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4] 2> 138 T15 oaz.Environment.logEnv Client environment:java.home=/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b79/jre
[junit4:junit4] 2> 138 T15 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> 140 T15 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> 140 T15 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4] 2> 141 T15 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4] 2> 141 T15 oaz.Environment.logEnv Client environment:os.name=Linux
[junit4:junit4] 2> 142 T15 oaz.Environment.logEnv Client environment:os.arch=i386
[junit4:junit4] 2> 142 T15 oaz.Environment.logEnv Client environment:os.version=3.2.0-38-generic
[junit4:junit4] 2> 143 T15 oaz.Environment.logEnv Client environment:user.name=jenkins
[junit4:junit4] 2> 143 T15 oaz.Environment.logEnv Client environment:user.home=/var/lib/jenkins
[junit4:junit4] 2> 144 T15 oaz.Environment.logEnv Client environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 146 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1755b57
[junit4:junit4] 2> 178 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 183 T21 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 185 T21 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 976 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43535
[junit4:junit4] 2> 984 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43535
[junit4:junit4] 2> 988 T19 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 999 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0000 with negotiated timeout 10000 for client /127.0.0.1:43535
[junit4:junit4] 2> 999 T21 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0000, negotiated timeout = 10000
[junit4:junit4] 2> 1001 T22 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1755b57 name:ZooKeeperConnection Watcher:127.0.0.1:36228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1002 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1003 T15 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 1031 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf0000
[junit4:junit4] 2> 1032 T22 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 1032 T15 oaz.ZooKeeper.close Session: 0x13d774839cf0000 closed
[junit4:junit4] 2> 1033 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43535 which had sessionid 0x13d774839cf0000
[junit4:junit4] 2> 1033 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c61227
[junit4:junit4] 2> 1034 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1034 T23 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 1035 T23 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 1035 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43536
[junit4:junit4] 2> 1036 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43536
[junit4:junit4] 2> 1037 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0001 with negotiated timeout 10000 for client /127.0.0.1:43536
[junit4:junit4] 2> 1037 T23 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0001, negotiated timeout = 10000
[junit4:junit4] 2> 1037 T24 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c61227 name:ZooKeeperConnection Watcher:127.0.0.1:36228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1038 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1043 T15 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 1048 T15 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 1052 T15 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 1056 T15 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 1060 T15 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> 1068 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 1075 T15 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> 1076 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 1083 T15 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> 1083 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 1091 T15 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> 1092 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 1096 T15 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> 1096 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 1101 T15 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> 1101 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 1106 T15 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> 1106 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 1111 T15 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> 1111 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 1115 T15 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> 1115 T15 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 1119 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf0001
[junit4:junit4] 2> 1120 T24 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 1120 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43536 which had sessionid 0x13d774839cf0001
[junit4:junit4] 2> 1120 T15 oaz.ZooKeeper.close Session: 0x13d774839cf0001 closed
[junit4:junit4] 2> 1321 T15 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 1409 T15 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58385
[junit4:junit4] 2> 1424 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 1425 T15 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 1425 T15 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-1363505855954
[junit4:junit4] 2> 1426 T15 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-1363505855954/solr.xml
[junit4:junit4] 2> 1426 T15 oasc.CoreContainer.<init> New CoreContainer 5079265
[junit4:junit4] 2> 1427 T15 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-1363505855954/'
[junit4:junit4] 2> 1427 T15 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-1363505855954/'
[junit4:junit4] 2> 1470 T15 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 1470 T15 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 1471 T15 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 1471 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 1471 T15 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 1472 T15 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 1472 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 1472 T15 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 1473 T15 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 1485 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 1706 T15 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 1721 T15 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36228/solr
[junit4:junit4] 2> 1729 T15 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 1730 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@5a26ff
[junit4:junit4] 2> 1731 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1732 T35 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 1732 T35 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 1733 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43537
[junit4:junit4] 2> 1733 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43537
[junit4:junit4] 2> 1735 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0002 with negotiated timeout 20000 for client /127.0.0.1:43537
[junit4:junit4] 2> 1735 T35 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0002, negotiated timeout = 20000
[junit4:junit4] 2> 1736 T36 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a26ff name:ZooKeeperConnection Watcher:127.0.0.1:36228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1736 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1738 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf0002
[junit4:junit4] 2> 1739 T36 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 1739 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43537 which had sessionid 0x13d774839cf0002
[junit4:junit4] 2> 1739 T15 oaz.ZooKeeper.close Session: 0x13d774839cf0002 closed
[junit4:junit4] 2> 1741 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 1745 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@161a1fb
[junit4:junit4] 2> 1746 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1747 T37 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 1747 T37 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 1747 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43538
[junit4:junit4] 2> 1748 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43538
[junit4:junit4] 2> 1749 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0003 with negotiated timeout 20000 for client /127.0.0.1:43538
[junit4:junit4] 2> 1749 T37 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0003, negotiated timeout = 20000
[junit4:junit4] 2> 1749 T38 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@161a1fb name:ZooKeeperConnection Watcher:127.0.0.1:36228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1750 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1755 T15 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4:junit4] 2> 1761 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:create cxid:0x7 zxid:0x1b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1762 T15 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4:junit4] 2> 1771 T15 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 1773 T15 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58385_
[junit4:junit4] 2> 1776 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:delete cxid:0x12 zxid:0x1e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58385_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58385_
[junit4:junit4] 2> 1777 T15 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58385_
[junit4:junit4] 2> 1783 T15 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 1795 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:delete cxid:0x20 zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4] 2> 1796 T15 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 1802 T15 oasc.Overseer.start Overseer (id=89358719708495875-127.0.0.1:58385_-n_0000000000) starting
[junit4:junit4] 2> 1805 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:create cxid:0x25 zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1814 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:create cxid:0x26 zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1815 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:create cxid:0x28 zxid:0x27 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1817 T15 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4:junit4] 2> 1824 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:create cxid:0x2e zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1826 T40 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4] 2> 1827 T15 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 1830 T15 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4:junit4] 2> 1833 T15 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 1842 T39 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 1866 T41 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-1363505855954/collection1
[junit4:junit4] 2> 1866 T41 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 1868 T41 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 1868 T41 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 1872 T41 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-1363505855954/collection1/'
[junit4:junit4] 2> 1873 T41 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-1363505855954/collection1/lib/README' to classloader
[junit4:junit4] 2> 1873 T41 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-1363505855954/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 1915 T41 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 1962 T41 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 1964 T41 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 1997 T41 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 2741 T41 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 2752 T41 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 2756 T41 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 2915 T41 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 2922 T41 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 2928 T41 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 2931 T41 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 2932 T41 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 2932 T41 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 2934 T41 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 2935 T41 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 2935 T41 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 2935 T41 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-1363505855954/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/control/data/
[junit4:junit4] 2> 2937 T41 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8952cb
[junit4:junit4] 2> 2938 T41 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 2939 T41 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/control/data forceNew: false
[junit4:junit4] 2> 2939 T41 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/control/data/index/
[junit4:junit4] 2> 2939 T41 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/control/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 2939 T41 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/control/data/index forceNew: false
[junit4:junit4] 2> 2941 T41 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@ada2e3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@142c82d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 2942 T41 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 2953 T41 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 2954 T41 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 2954 T41 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 2955 T41 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 2956 T41 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 2956 T41 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 2956 T41 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 2957 T41 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 2957 T41 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 2960 T41 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 2963 T41 oass.SolrIndexSearcher.<init> Opening Searcher@327b85 main
[junit4:junit4] 2> 2966 T41 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/control/data/tlog
[junit4:junit4] 2> 2971 T41 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 2972 T41 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 2976 T42 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@327b85 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 2979 T41 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 2979 T41 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 3349 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3350 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:58385",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "node_name":"127.0.0.1:58385_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 3351 T39 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 3358 T39 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 3368 T38 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> 3981 T41 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 3982 T41 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:58385 collection:control_collection shard:shard1
[junit4:junit4] 2> 3990 T41 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4] 2> 4002 T41 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 4019 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:delete cxid:0x82 zxid:0x35 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4] 2> 4020 T41 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 4022 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:create cxid:0x83 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4028 T41 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 4028 T41 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 4031 T41 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58385/collection1/
[junit4:junit4] 2> 4031 T41 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 4032 T41 oasc.SyncStrategy.syncToMe http://127.0.0.1:58385/collection1/ has no replicas
[junit4:junit4] 2> 4032 T41 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58385/collection1/
[junit4:junit4] 2> 4033 T41 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 4043 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0003 type:create cxid:0x8e zxid:0x3a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4874 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4892 T38 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> 4901 T41 oasc.ZkController.register We are http://127.0.0.1:58385/collection1/ and leader is http://127.0.0.1:58385/collection1/
[junit4:junit4] 2> 4902 T41 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58385
[junit4:junit4] 2> 4903 T41 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 4903 T41 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 4903 T41 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 4905 T41 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4908 T15 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 4908 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 4921 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 4927 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 4929 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1603373
[junit4:junit4] 2> 4930 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4930 T44 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 4931 T44 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 4931 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43540
[junit4:junit4] 2> 4931 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43540
[junit4:junit4] 2> 4932 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0004 with negotiated timeout 10000 for client /127.0.0.1:43540
[junit4:junit4] 2> 4932 T44 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0004, negotiated timeout = 10000
[junit4:junit4] 2> 4933 T45 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1603373 name:ZooKeeperConnection Watcher:127.0.0.1:36228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4933 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4935 T15 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 4940 T15 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 5001 T15 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 5003 T15 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:32930
[junit4:junit4] 2> 5004 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 5004 T15 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 5005 T15 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-1363505859773
[junit4:junit4] 2> 5005 T15 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-1363505859773/solr.xml
[junit4:junit4] 2> 5005 T15 oasc.CoreContainer.<init> New CoreContainer 7594021
[junit4:junit4] 2> 5006 T15 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-1363505859773/'
[junit4:junit4] 2> 5006 T15 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-1363505859773/'
[junit4:junit4] 2> 5027 T15 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 5027 T15 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 5027 T15 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 5028 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 5028 T15 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 5029 T15 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 5029 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 5029 T15 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 5030 T15 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 5030 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 5038 T15 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 5051 T15 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36228/solr
[junit4:junit4] 2> 5052 T15 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 5052 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@ee1985
[junit4:junit4] 2> 5053 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 5053 T55 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 5054 T55 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 5054 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43542
[junit4:junit4] 2> 5055 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43542
[junit4:junit4] 2> 5056 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0005 with negotiated timeout 20000 for client /127.0.0.1:43542
[junit4:junit4] 2> 5056 T55 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0005, negotiated timeout = 20000
[junit4:junit4] 2> 5056 T56 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ee1985 name:ZooKeeperConnection Watcher:127.0.0.1:36228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 5057 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 5058 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf0005
[junit4:junit4] 2> 5059 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43542 which had sessionid 0x13d774839cf0005
[junit4:junit4] 2> 5059 T15 oaz.ZooKeeper.close Session: 0x13d774839cf0005 closed
[junit4:junit4] 2> 5059 T56 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 5060 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 5063 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@70e64
[junit4:junit4] 2> 5065 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 5065 T57 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 5065 T57 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 5065 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43543
[junit4:junit4] 2> 5066 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43543
[junit4:junit4] 2> 5067 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0006 with negotiated timeout 20000 for client /127.0.0.1:43543
[junit4:junit4] 2> 5067 T57 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0006, negotiated timeout = 20000
[junit4:junit4] 2> 5067 T58 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@70e64 name:ZooKeeperConnection Watcher:127.0.0.1:36228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 5067 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 5069 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:create cxid:0x1 zxid:0x48 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 5070 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:create cxid:0x3 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 5072 T15 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 6076 T15 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32930_
[junit4:junit4] 2> 6077 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:delete cxid:0xd zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:32930_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:32930_
[junit4:junit4] 2> 6077 T15 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32930_
[junit4:junit4] 2> 6080 T38 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> 6081 T45 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 6081 T58 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 6089 T38 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 6095 T59 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-1363505859773/collection1
[junit4:junit4] 2> 6095 T59 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 6096 T59 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 6096 T59 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 6098 T59 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-1363505859773/collection1/'
[junit4:junit4] 2> 6099 T59 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-1363505859773/collection1/lib/README' to classloader
[junit4:junit4] 2> 6099 T59 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-1363505859773/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 6138 T59 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 6183 T59 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 6185 T59 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 6192 T59 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 6402 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6403 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:58385__collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:58385",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "node_name":"127.0.0.1:58385_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 6409 T45 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> 6409 T38 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> 6409 T58 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> 6657 T59 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 6667 T59 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 6670 T59 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 6695 T59 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 6702 T59 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 6706 T59 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 6707 T59 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 6708 T59 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 6708 T59 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 6709 T59 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 6709 T59 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 6710 T59 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 6710 T59 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-1363505859773/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty1/
[junit4:junit4] 2> 6710 T59 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8952cb
[junit4:junit4] 2> 6711 T59 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 6711 T59 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty1 forceNew: false
[junit4:junit4] 2> 6712 T59 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty1/index/
[junit4:junit4] 2> 6712 T59 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 6712 T59 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty1/index forceNew: false
[junit4:junit4] 2> 6714 T59 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@642093 lockFactory=org.apache.lucene.store.NativeFSLockFactory@186c031),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 6714 T59 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 6716 T59 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 6716 T59 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 6717 T59 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 6718 T59 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 6719 T59 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 6719 T59 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 6719 T59 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 6720 T59 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 6720 T59 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 6723 T59 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 6726 T59 oass.SolrIndexSearcher.<init> Opening Searcher@1443c80 main
[junit4:junit4] 2> 6726 T59 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty1/tlog
[junit4:junit4] 2> 6727 T59 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 6727 T59 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 6733 T60 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1443c80 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 6735 T59 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 6736 T59 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 7914 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7915 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:32930",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "node_name":"127.0.0.1:32930_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 7916 T39 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4] 2> 7916 T39 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 7995 T58 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> 7995 T45 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> 7995 T38 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> 8737 T59 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 8738 T59 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:32930 collection:collection1 shard:shard1
[junit4:junit4] 2> 8739 T59 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 8748 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:delete cxid:0x4a zxid:0x5a txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4] 2> 8749 T59 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 8749 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:create cxid:0x4b zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8752 T59 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 8753 T59 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 8753 T59 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32930/collection1/
[junit4:junit4] 2> 8753 T59 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 8753 T59 oasc.SyncStrategy.syncToMe http://127.0.0.1:32930/collection1/ has no replicas
[junit4:junit4] 2> 8753 T59 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32930/collection1/
[junit4:junit4] 2> 8754 T59 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 8760 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:create cxid:0x56 zxid:0x5f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9502 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9520 T58 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> 9520 T45 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> 9522 T38 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> 9565 T59 oasc.ZkController.register We are http://127.0.0.1:32930/collection1/ and leader is http://127.0.0.1:32930/collection1/
[junit4:junit4] 2> 9565 T59 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32930
[junit4:junit4] 2> 9565 T59 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 9566 T59 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 9566 T59 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 9569 T59 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9572 T15 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 9573 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 9574 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 9643 T15 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 9646 T15 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:35794
[junit4:junit4] 2> 9647 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 9647 T15 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 9648 T15 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-1363505864412
[junit4:junit4] 2> 9648 T15 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-1363505864412/solr.xml
[junit4:junit4] 2> 9648 T15 oasc.CoreContainer.<init> New CoreContainer 8968973
[junit4:junit4] 2> 9649 T15 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-1363505864412/'
[junit4:junit4] 2> 9649 T15 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-1363505864412/'
[junit4:junit4] 2> 9672 T15 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 9672 T15 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 9672 T15 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 9673 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 9673 T15 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 9674 T15 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 9674 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 9674 T15 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 9675 T15 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 9675 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 9687 T15 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 9701 T15 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36228/solr
[junit4:junit4] 2> 9702 T15 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 9702 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1220906
[junit4:junit4] 2> 9703 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 9703 T71 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 9704 T71 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 9704 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43544
[junit4:junit4] 2> 9705 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43544
[junit4:junit4] 2> 9706 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0007 with negotiated timeout 20000 for client /127.0.0.1:43544
[junit4:junit4] 2> 9706 T71 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0007, negotiated timeout = 20000
[junit4:junit4] 2> 9706 T72 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1220906 name:ZooKeeperConnection Watcher:127.0.0.1:36228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 9706 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 9708 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf0007
[junit4:junit4] 2> 9709 T72 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 9709 T15 oaz.ZooKeeper.close Session: 0x13d774839cf0007 closed
[junit4:junit4] 2> 9709 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43544 which had sessionid 0x13d774839cf0007
[junit4:junit4] 2> 9709 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 9712 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@7a90d0
[junit4:junit4] 2> 9713 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 9714 T73 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 9714 T73 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 9715 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43545
[junit4:junit4] 2> 9715 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43545
[junit4:junit4] 2> 9716 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0008 with negotiated timeout 20000 for client /127.0.0.1:43545
[junit4:junit4] 2> 9716 T73 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0008, negotiated timeout = 20000
[junit4:junit4] 2> 9717 T74 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a90d0 name:ZooKeeperConnection Watcher:127.0.0.1:36228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 9718 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 9719 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0008 type:create cxid:0x1 zxid:0x6c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9720 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0008 type:create cxid:0x3 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9722 T15 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 10726 T15 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35794_
[junit4:junit4] 2> 10727 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0008 type:delete cxid:0xd zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:35794_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:35794_
[junit4:junit4] 2> 10727 T15 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:35794_
[junit4:junit4] 2> 10730 T45 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> 10730 T58 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> 10730 T38 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10730 T74 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10731 T38 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> 10733 T58 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10734 T45 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10738 T75 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-1363505864412/collection1
[junit4:junit4] 2> 10738 T75 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 10739 T75 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 10739 T75 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 10741 T75 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-1363505864412/collection1/'
[junit4:junit4] 2> 10741 T75 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-1363505864412/collection1/lib/README' to classloader
[junit4:junit4] 2> 10742 T75 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-1363505864412/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 10775 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 10819 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 10820 T75 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 10826 T75 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 11032 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 11033 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:32930__collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:32930",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "node_name":"127.0.0.1:32930_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 11037 T58 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> 11037 T74 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> 11037 T38 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> 11037 T45 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> 11302 T75 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 11309 T75 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 11312 T75 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 11334 T75 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 11339 T75 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 11343 T75 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 11346 T75 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 11347 T75 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 11347 T75 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 11349 T75 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 11349 T75 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 11349 T75 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 11349 T75 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-1363505864412/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty2/
[junit4:junit4] 2> 11350 T75 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8952cb
[junit4:junit4] 2> 11350 T75 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 11351 T75 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty2 forceNew: false
[junit4:junit4] 2> 11351 T75 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty2/index/
[junit4:junit4] 2> 11351 T75 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 11351 T75 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty2/index forceNew: false
[junit4:junit4] 2> 11353 T75 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a83c7c lockFactory=org.apache.lucene.store.NativeFSLockFactory@d3570d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 11353 T75 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 11355 T75 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 11356 T75 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 11356 T75 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 11357 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 11358 T75 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 11358 T75 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 11358 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 11359 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 11359 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 11362 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 11378 T75 oass.SolrIndexSearcher.<init> Opening Searcher@f6e08e main
[junit4:junit4] 2> 11378 T75 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty2/tlog
[junit4:junit4] 2> 11379 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 11379 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 11384 T76 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f6e08e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 11387 T75 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 11387 T75 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 12542 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 12543 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:35794",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "node_name":"127.0.0.1:35794_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 12544 T39 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4] 2> 12544 T39 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4] 2> 12591 T58 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> 12591 T45 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> 12591 T74 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> 12591 T38 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> 13389 T75 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 13390 T75 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:35794 collection:collection1 shard:shard2
[junit4:junit4] 2> 13392 T75 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4] 2> 13405 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0008 type:delete cxid:0x49 zxid:0x7d txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4] 2> 13406 T75 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 13407 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0008 type:create cxid:0x4a zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 13412 T75 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 13412 T75 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 13413 T75 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:35794/collection1/
[junit4:junit4] 2> 13413 T75 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 13414 T75 oasc.SyncStrategy.syncToMe http://127.0.0.1:35794/collection1/ has no replicas
[junit4:junit4] 2> 13414 T75 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:35794/collection1/
[junit4:junit4] 2> 13415 T75 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4] 2> 13421 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0008 type:create cxid:0x54 zxid:0x81 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 14101 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 14119 T38 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> 14119 T74 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> 14120 T45 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> 14120 T58 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> 14128 T75 oasc.ZkController.register We are http://127.0.0.1:35794/collection1/ and leader is http://127.0.0.1:35794/collection1/
[junit4:junit4] 2> 14129 T75 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:35794
[junit4:junit4] 2> 14129 T75 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 14129 T75 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 14130 T75 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 14133 T75 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 14136 T15 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 14137 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 14138 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 14221 T15 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 14224 T15 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60576
[junit4:junit4] 2> 14225 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 14225 T15 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 14225 T15 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-1363505868980
[junit4:junit4] 2> 14226 T15 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-1363505868980/solr.xml
[junit4:junit4] 2> 14226 T15 oasc.CoreContainer.<init> New CoreContainer 12296344
[junit4:junit4] 2> 14227 T15 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-1363505868980/'
[junit4:junit4] 2> 14227 T15 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-1363505868980/'
[junit4:junit4] 2> 14247 T15 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 14248 T15 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 14248 T15 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 14248 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 14249 T15 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 14249 T15 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 14249 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 14250 T15 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 14250 T15 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 14251 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 14258 T15 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 14271 T15 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36228/solr
[junit4:junit4] 2> 14271 T15 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 14272 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@823e65
[junit4:junit4] 2> 14273 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 14273 T87 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 14274 T87 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 14275 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43549
[junit4:junit4] 2> 14275 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43549
[junit4:junit4] 2> 14277 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf0009 with negotiated timeout 20000 for client /127.0.0.1:43549
[junit4:junit4] 2> 14277 T87 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf0009, negotiated timeout = 20000
[junit4:junit4] 2> 14278 T88 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@823e65 name:ZooKeeperConnection Watcher:127.0.0.1:36228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 14278 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 14280 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf0009
[junit4:junit4] 2> 14281 T88 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 14281 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43549 which had sessionid 0x13d774839cf0009
[junit4:junit4] 2> 14281 T15 oaz.ZooKeeper.close Session: 0x13d774839cf0009 closed
[junit4:junit4] 2> 14282 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 14288 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@90f128
[junit4:junit4] 2> 14289 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 14289 T89 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 14290 T89 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 14290 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43550
[junit4:junit4] 2> 14291 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43550
[junit4:junit4] 2> 14292 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf000a with negotiated timeout 20000 for client /127.0.0.1:43550
[junit4:junit4] 2> 14292 T89 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf000a, negotiated timeout = 20000
[junit4:junit4] 2> 14292 T90 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@90f128 name:ZooKeeperConnection Watcher:127.0.0.1:36228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 14293 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 14294 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf000a type:create cxid:0x1 zxid:0x8e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 14296 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf000a type:create cxid:0x3 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 14300 T15 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 15305 T15 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60576_
[junit4:junit4] 2> 15307 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf000a type:delete cxid:0xd zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60576_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60576_
[junit4:junit4] 2> 15308 T15 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60576_
[junit4:junit4] 2> 15312 T45 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> 15312 T74 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> 15314 T90 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 15314 T38 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 15314 T58 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 15315 T38 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> 15316 T58 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> 15317 T45 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 15317 T74 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4] 2> 15327 T91 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-1363505868980/collection1
[junit4:junit4] 2> 15328 T91 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 15329 T91 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 15329 T91 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 15330 T91 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-1363505868980/collection1/'
[junit4:junit4] 2> 15331 T91 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-1363505868980/collection1/lib/README' to classloader
[junit4:junit4] 2> 15331 T91 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-1363505868980/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 15360 T91 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 15401 T91 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 15403 T91 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 15408 T91 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 15630 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 15631 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:35794__collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:35794",
[junit4:junit4] 2> "shard":"shard2",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "node_name":"127.0.0.1:35794_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 15636 T90 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> 15637 T58 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> 15637 T38 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> 15637 T74 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> 15637 T45 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> 15844 T91 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 15851 T91 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 15855 T91 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 15884 T91 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 15889 T91 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 15894 T91 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 15896 T91 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 15896 T91 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 15897 T91 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 15898 T91 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 15898 T91 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 15899 T91 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 15899 T91 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-1363505868980/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty3/
[junit4:junit4] 2> 15899 T91 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8952cb
[junit4:junit4] 2> 15900 T91 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 15901 T91 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty3 forceNew: false
[junit4:junit4] 2> 15901 T91 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty3/index/
[junit4:junit4] 2> 15902 T91 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 15902 T91 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty3/index forceNew: false
[junit4:junit4] 2> 15904 T91 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15b9db9 lockFactory=org.apache.lucene.store.NativeFSLockFactory@26ae02),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 15904 T91 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 15907 T91 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 15908 T91 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 15908 T91 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 15909 T91 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 15910 T91 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 15911 T91 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 15911 T91 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 15912 T91 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 15912 T91 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 15916 T91 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 15920 T91 oass.SolrIndexSearcher.<init> Opening Searcher@12e6be1 main
[junit4:junit4] 2> 15921 T91 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty3/tlog
[junit4:junit4] 2> 15922 T91 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 15922 T91 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 15933 T92 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12e6be1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 15936 T91 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 15936 T91 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 17144 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 17146 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:60576",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "node_name":"127.0.0.1:60576_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 17146 T39 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4] 2> 17146 T39 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 17154 T90 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> 17155 T38 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> 17155 T45 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> 17154 T58 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> 17154 T74 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> 17938 T91 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 17939 T91 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60576 collection:collection1 shard:shard1
[junit4:junit4] 2> 17946 T91 oasc.ZkController.register We are http://127.0.0.1:60576/collection1/ and leader is http://127.0.0.1:32930/collection1/
[junit4:junit4] 2> 17946 T91 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60576
[junit4:junit4] 2> 17947 T91 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 17947 T91 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@17f4507 url=http://127.0.0.1:60576/collection1 node=127.0.0.1:60576_ C0_STATE=coll:collection1 core:collection1 props:{collection=collection1, base_url=http://127.0.0.1:60576, state=down, node_name=127.0.0.1:60576_, core=collection1}
[junit4:junit4] 2> 17954 T93 C0 P60576 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 17955 T91 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 17957 T93 C0 P60576 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 17958 T93 C0 P60576 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 17958 T93 C0 P60576 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 17959 T15 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 17960 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 17961 T93 C0 P60576 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 17961 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 18041 T15 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 18044 T15 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49232
[junit4:junit4] 2> 18045 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 18045 T15 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 18046 T15 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-jetty4-1363505872801
[junit4:junit4] 2> 18046 T15 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-jetty4-1363505872801/solr.xml
[junit4:junit4] 2> 18046 T15 oasc.CoreContainer.<init> New CoreContainer 8926993
[junit4:junit4] 2> 18047 T15 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-jetty4-1363505872801/'
[junit4:junit4] 2> 18047 T15 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-jetty4-1363505872801/'
[junit4:junit4] 2> 18068 T15 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 18069 T15 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 18069 T15 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 18069 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 18070 T15 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 18070 T15 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 18071 T15 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 18071 T15 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 18072 T15 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 18072 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 18080 T15 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 18104 T15 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36228/solr
[junit4:junit4] 2> 18105 T15 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 18105 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@eadcf
[junit4:junit4] 2> 18106 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 18106 T104 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 18108 T104 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 18108 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43557
[junit4:junit4] 2> 18109 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43557
[junit4:junit4] 2> 18109 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf000b with negotiated timeout 20000 for client /127.0.0.1:43557
[junit4:junit4] 2> 18109 T104 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf000b, negotiated timeout = 20000
[junit4:junit4] 2> 18110 T105 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@eadcf name:ZooKeeperConnection Watcher:127.0.0.1:36228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 18110 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 18111 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf000b
[junit4:junit4] 2> 18122 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43557 which had sessionid 0x13d774839cf000b
[junit4:junit4] 2> 18122 T15 oaz.ZooKeeper.close Session: 0x13d774839cf000b closed
[junit4:junit4] 2> 18122 T105 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 18123 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 18128 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@12c3f01
[junit4:junit4] 2> 18129 T106 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 18129 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 18129 T106 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 18129 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43558
[junit4:junit4] 2> 18130 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43558
[junit4:junit4] 2> 18132 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf000c with negotiated timeout 20000 for client /127.0.0.1:43558
[junit4:junit4] 2> 18132 T106 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf000c, negotiated timeout = 20000
[junit4:junit4] 2> 18133 T107 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12c3f01 name:ZooKeeperConnection Watcher:127.0.0.1:36228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 18133 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 18134 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf000c type:create cxid:0x1 zxid:0xa1 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 18136 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf000c type:create cxid:0x3 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 18138 T15 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 18662 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 18663 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:60576__collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:60576",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "state":"recovering",
[junit4:junit4] 2> "node_name":"127.0.0.1:60576_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 18669 T90 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> 18670 T107 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> 18670 T45 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> 18670 T74 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> 18669 T58 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> 18670 T38 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> 19141 T15 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49232_
[junit4:junit4] 2> 19143 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf000c type:delete cxid:0xf zxid:0xa7 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49232_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49232_
[junit4:junit4] 2> 19144 T15 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49232_
[junit4:junit4] 2> 19148 T90 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> 19148 T45 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> 19148 T58 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 19149 T107 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 19149 T38 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 19149 T107 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 19148 T74 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 19150 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 19149 T58 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 19150 T38 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 19151 T90 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 19153 T45 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4] 2> 19161 T108 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-jetty4-1363505872801/collection1
[junit4:junit4] 2> 19161 T108 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 19163 T108 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 19163 T108 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 19166 T108 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-jetty4-1363505872801/collection1/'
[junit4:junit4] 2> 19167 T108 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363505872801/collection1/lib/README' to classloader
[junit4:junit4] 2> 19168 T108 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363505872801/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 19235 T108 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 19246 T54 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {action=PREPRECOVERY&state=recovering&nodeName=127.0.0.1:60576_&onlyIfLeader=true&wt=javabin&version=2&checkLive=true&coreNodeName=127.0.0.1:60576__collection1&core=collection1} status=0 QTime=1002
[junit4:junit4] 2> 19280 T108 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 19281 T108 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 19290 T108 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 19750 T108 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 19757 T108 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 19760 T108 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 19779 T108 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 19783 T108 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 19786 T108 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 19788 T108 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 19788 T108 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 19789 T108 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 19790 T108 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 19790 T108 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 19790 T108 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 19791 T108 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-jetty4-1363505872801/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty4/
[junit4:junit4] 2> 19791 T108 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8952cb
[junit4:junit4] 2> 19791 T108 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 19792 T108 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty4 forceNew: false
[junit4:junit4] 2> 19792 T108 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty4/index/
[junit4:junit4] 2> 19792 T108 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 19792 T108 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty4/index forceNew: false
[junit4:junit4] 2> 19794 T108 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@18929dc lockFactory=org.apache.lucene.store.NativeFSLockFactory@145e60d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 19794 T108 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 19796 T108 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 19796 T108 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 19797 T108 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 19797 T108 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 19798 T108 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 19798 T108 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 19799 T108 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 19799 T108 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 19800 T108 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 19802 T108 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 19809 T108 oass.SolrIndexSearcher.<init> Opening Searcher@1817cf5 main
[junit4:junit4] 2> 19810 T108 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty4/tlog
[junit4:junit4] 2> 19810 T108 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 19811 T108 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 19816 T109 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1817cf5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 19819 T108 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 19819 T108 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 20177 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 20179 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49232",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "node_name":"127.0.0.1:49232_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 20180 T39 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4] 2> 20180 T39 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4] 2> 20281 T45 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 20281 T38 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 20281 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 20281 T90 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 20281 T58 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 20282 T107 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 20821 T108 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 20821 T108 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49232 collection:collection1 shard:shard2
[junit4:junit4] 2> 20828 T108 oasc.ZkController.register We are http://127.0.0.1:49232/collection1/ and leader is http://127.0.0.1:35794/collection1/
[junit4:junit4] 2> 20828 T108 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49232
[junit4:junit4] 2> 20829 T108 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 20829 T108 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@1cecfde url=http://127.0.0.1:49232/collection1 node=127.0.0.1:49232_ C1_STATE=coll:collection1 core:collection1 props:{collection=collection1, base_url=http://127.0.0.1:49232, state=down, node_name=127.0.0.1:49232_, core=collection1}
[junit4:junit4] 2> 20830 T110 C1 P49232 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 20831 T108 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 20831 T110 C1 P49232 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 20832 T110 C1 P49232 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 20832 T110 C1 P49232 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 20833 T15 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 20834 T15 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 20834 T110 C1 P49232 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 20835 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 20841 T15 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 20843 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 20848 T53 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-1363505859773/onenodecollectioncore
[junit4:junit4] 2> 20848 T53 oasc.ZkController.createCollectionZkNode Check for collection zkNode:onenodecollection
[junit4:junit4] 2> 20850 T53 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:onenodecollection
[junit4:junit4] 2> 20850 T53 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4] 2> 20852 T53 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4] 2> 20852 T53 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection
[junit4:junit4] 2> 20855 T53 oasc.ZkController.readConfigName Load collection config from:/collections/onenodecollection
[junit4:junit4] 2> 20857 T53 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-1363505859773/onenodecollectioncore/'
[junit4:junit4] 2> 20896 T53 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 20949 T53 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 20951 T53 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 20956 T53 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> C0_STATE=coll:collection1 core:collection1 props:{collection=collection1, base_url=http://127.0.0.1:60576, shard=shard1, state=recovering, node_name=127.0.0.1:60576_, core=collection1}
[junit4:junit4] 2> 21290 T93 C0 P60576 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:32930/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4] 2> 21293 T93 C0 P60576 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4] 2> 21298 T93 C0 P60576 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60576 START replicas=[http://127.0.0.1:32930/collection1/] nUpdates=100
[junit4:junit4] 2> 21300 T93 C0 P60576 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4] 2> 21301 T93 C0 P60576 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4] 2> 21301 T93 C0 P60576 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4] 2> 21301 T93 C0 P60576 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4] 2> 21301 T93 C0 P60576 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4] 2> 21301 T93 C0 P60576 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:32930/collection1/. core=collection1
[junit4:junit4] 2> 21302 T93 C0 P60576 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@a462d0 url=http://127.0.0.1:32930/collection1 node=127.0.0.1:32930_ C2_STATE=coll:collection1 core:collection1 props:{collection=collection1, base_url=http://127.0.0.1:32930, shard=shard1, state=active, node_name=127.0.0.1:32930_, core=collection1, leader=true}
[junit4:junit4] 2> 21326 T51 C2 P32930 REQ /get {getVersions=100&distrib=false&wt=javabin&version=2&qt=/get} status=0 QTime=5
[junit4:junit4] 2> 21335 T52 C2 P32930 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 21340 T52 C2 P32930 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@642093 lockFactory=org.apache.lucene.store.NativeFSLockFactory@186c031),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 21340 T52 C2 P32930 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 21341 T52 C2 P32930 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@642093 lockFactory=org.apache.lucene.store.NativeFSLockFactory@186c031),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@642093 lockFactory=org.apache.lucene.store.NativeFSLockFactory@186c031),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 21342 T52 C2 P32930 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4] 2> 21342 T52 C2 P32930 oass.SolrIndexSearcher.<init> Opening Searcher@a2ea0e realtime
[junit4:junit4] 2> 21343 T52 C2 P32930 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 21343 T52 C2 P32930 /update {softCommit=false&waitSearcher=true&wt=javabin&openSearcher=false&commit_end_point=true&version=2&commit=true} {commit=} 0 22
[junit4:junit4] 2> 21367 T93 C0 P60576 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 21368 T93 C0 P60576 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4] 2> 21372 T93 C0 P60576 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 21384 T51 C2 P32930 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 21385 T51 C2 P32930 REQ /replication {wt=javabin&version=2&qt=/replication&command=indexversion} status=0 QTime=11
[junit4:junit4] 2> 21386 T93 C0 P60576 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4] 2> 21386 T93 C0 P60576 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 21386 T93 C0 P60576 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 21389 T52 C2 P32930 REQ /replication {generation=2&wt=javabin&version=2&qt=/replication&command=filelist} status=0 QTime=0
[junit4:junit4] 2> 21390 T93 C0 P60576 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4] 2> 21391 T93 C0 P60576 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty3/index.20130317133756223 forceNew: false
[junit4:junit4] 2> 21391 T93 C0 P60576 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@156884f lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ccf3d9) fullCopy=false
[junit4:junit4] 2> 21397 T54 C2 P32930 REQ /replication {file=segments_2&checksum=true&generation=2&wt=filestream&qt=/replication&command=filecontent} status=0 QTime=1
[junit4:junit4] 2> 21402 T93 C0 P60576 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 21410 T93 C0 P60576 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 21410 T93 C0 P60576 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 21412 T93 C0 P60576 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15b9db9 lockFactory=org.apache.lucene.store.NativeFSLockFactory@26ae02),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@15b9db9 lockFactory=org.apache.lucene.store.NativeFSLockFactory@26ae02),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 21412 T93 C0 P60576 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4] 2> 21413 T93 C0 P60576 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 21413 T93 C0 P60576 oass.SolrIndexSearcher.<init> Opening Searcher@1fd197d main
[junit4:junit4] 2> 21416 T92 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fd197d main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4] 2> 21416 T93 C0 P60576 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@156884f lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ccf3d9)
[junit4:junit4] 2> 21417 T93 C0 P60576 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4] 2> 21417 T93 C0 P60576 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4] 2> 21417 T93 C0 P60576 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 21417 T93 C0 P60576 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 21419 T93 C0 P60576 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4] 2> 21432 T53 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 21439 T53 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 21442 T53 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 21467 T53 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 21478 T53 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 21486 T53 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 21489 T53 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 21489 T53 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 21490 T53 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 21492 T53 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 21493 T53 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 21494 T53 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 21495 T53 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-1363505859773/onenodecollectioncore/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363505854839/onenodecollection/
[junit4:junit4] 2> 21495 T53 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8952cb
[junit4:junit4] 2> 21496 T53 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 21498 T53 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363505854839/onenodecollection forceNew: false
[junit4:junit4] 2> 21498 T53 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-1363505854839/onenodecollection/index/
[junit4:junit4] 2> 21499 T53 oasc.SolrCore.initIndex WARNING [onenodecollectioncore] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363505854839/onenodecollection/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 21500 T53 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363505854839/onenodecollection/index forceNew: false
[junit4:junit4] 2> 21503 T53 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a7bd0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@84bfb8),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 21504 T53 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 21508 T53 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 21508 T53 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 21510 T53 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 21511 T53 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 21512 T53 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 21513 T53 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 21513 T53 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 21515 T53 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 21516 T53 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 21521 T53 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 21524 T53 oass.SolrIndexSearcher.<init> Opening Searcher@8a6144 main
[junit4:junit4] 2> 21525 T53 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363505854839/onenodecollection/tlog
[junit4:junit4] 2> 21525 T53 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 21526 T53 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 21531 T114 oasc.SolrCore.registerSearcher [onenodecollectioncore] Registered new searcher Searcher@8a6144 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 21537 T53 oasc.ZkController.publish publishing core=onenodecollectioncore state=down
[junit4:junit4] 2> 21787 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 21788 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:49232__collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49232",
[junit4:junit4] 2> "shard":"shard2",
[junit4:junit4] 2> "state":"recovering",
[junit4:junit4] 2> "node_name":"127.0.0.1:49232_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 21792 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:60576__collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:60576",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "node_name":"127.0.0.1:60576_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 21797 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "core_node_name":null,
[junit4:junit4] 2> "collection":"onenodecollection",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:32930",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "node_name":"127.0.0.1:32930_",
[junit4:junit4] 2> "roles":"none",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "core":"onenodecollectioncore"}
[junit4:junit4] 2> 21798 T39 oasc.Overseer$ClusterStateUpdater.createCollection Create collection onenodecollection with numShards 1
[junit4:junit4] 2> 21798 T39 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 21821 T38 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 21821 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 21821 T45 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 21821 T107 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 21821 T90 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 21821 T58 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 21843 T69 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {action=PREPRECOVERY&state=recovering&nodeName=127.0.0.1:49232_&onlyIfLeader=true&wt=javabin&version=2&checkLive=true&coreNodeName=127.0.0.1:49232__collection1&core=collection1} status=0 QTime=1000
[junit4:junit4] 2> 22540 T53 oasc.CoreContainer.registerCore registering core: onenodecollectioncore
[junit4:junit4] 2> 22540 T53 oasc.ZkController.register Register replica - core:onenodecollectioncore address:http://127.0.0.1:32930 collection:onenodecollection shard:shard1
[junit4:junit4] 2> 22541 T53 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leader_elect/shard1/election
[junit4:junit4] 2> 22553 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:delete cxid:0xac zxid:0xc2 txntype:-1 reqpath:n/a Error Path:/solr/collections/onenodecollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/onenodecollection/leaders
[junit4:junit4] 2> 22554 T53 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 22555 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:create cxid:0xad zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 22559 T53 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 22560 T53 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 22560 T53 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32930/onenodecollectioncore/
[junit4:junit4] 2> 22560 T53 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 22560 T53 oasc.SyncStrategy.syncToMe http://127.0.0.1:32930/onenodecollectioncore/ has no replicas
[junit4:junit4] 2> 22561 T53 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32930/onenodecollectioncore/
[junit4:junit4] 2> 22561 T53 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leaders/shard1
[junit4:junit4] 2> 22566 T20 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d774839cf0006 type:create cxid:0xb8 zxid:0xc7 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 23331 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 23345 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 23345 T38 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 23345 T45 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 23345 T107 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 23345 T58 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 23345 T90 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 23372 T53 oasc.ZkController.register We are http://127.0.0.1:32930/onenodecollectioncore/ and leader is http://127.0.0.1:32930/onenodecollectioncore/
[junit4:junit4] 2> 23373 T53 oasc.ZkController.register No LogReplay needed for core=onenodecollectioncore baseURL=http://127.0.0.1:32930
[junit4:junit4] 2> 23374 T53 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 23374 T53 oasc.ZkController.publish publishing core=onenodecollectioncore state=active
[junit4:junit4] 2> 23377 T53 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 23379 T53 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {collection=onenodecollection&action=CREATE&wt=javabin&version=2&roles=none&numShards=1&name=onenodecollectioncore&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363505854839/onenodecollection} status=0 QTime=2531
[junit4:junit4] 2> 23381 T15 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: onenodecollection failOnTimeout:true timeout (sec):230
[junit4:junit4] 2> 23382 T15 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> C1_STATE=coll:collection1 core:collection1 props:{collection=collection1, base_url=http://127.0.0.1:49232, shard=shard2, state=recovering, node_name=127.0.0.1:49232_, core=collection1}
[junit4:junit4] 2> 23844 T110 C1 P49232 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:35794/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4] 2> 23844 T110 C1 P49232 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:49232 START replicas=[http://127.0.0.1:35794/collection1/] nUpdates=100
[junit4:junit4] 2> 23845 T110 C1 P49232 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4] 2> 23845 T110 C1 P49232 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4] 2> 23846 T110 C1 P49232 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4] 2> 23846 T110 C1 P49232 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4] 2> 23847 T110 C1 P49232 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4] 2> 23848 T110 C1 P49232 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:35794/collection1/. core=collection1
[junit4:junit4] 2> 23848 T110 C1 P49232 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> ASYNC NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@1b4d0c url=http://127.0.0.1:35794/collection1 node=127.0.0.1:35794_ C3_STATE=coll:collection1 core:collection1 props:{collection=collection1, base_url=http://127.0.0.1:35794, shard=shard2, state=active, node_name=127.0.0.1:35794_, core=collection1, leader=true}
[junit4:junit4] 2> 23857 T67 C3 P35794 REQ /get {getVersions=100&distrib=false&wt=javabin&version=2&qt=/get} status=0 QTime=1
[junit4:junit4] 2> 23858 T70 C3 P35794 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 2> 23862 T70 C3 P35794 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a83c7c lockFactory=org.apache.lucene.store.NativeFSLockFactory@d3570d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 23863 T70 C3 P35794 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 23864 T70 C3 P35794 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a83c7c lockFactory=org.apache.lucene.store.NativeFSLockFactory@d3570d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@a83c7c lockFactory=org.apache.lucene.store.NativeFSLockFactory@d3570d),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 23865 T70 C3 P35794 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4] 2> 23866 T70 C3 P35794 oass.SolrIndexSearcher.<init> Opening Searcher@3876f8 realtime
[junit4:junit4] 2> 23867 T70 C3 P35794 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4] 2> 23867 T70 C3 P35794 /update {softCommit=false&waitSearcher=true&wt=javabin&openSearcher=false&commit_end_point=true&version=2&commit=true} {commit=} 0 9
[junit4:junit4] 2> 23869 T110 C1 P49232 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 23870 T110 C1 P49232 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4] 2> 23874 T68 C3 P35794 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4:junit4] 2> 23875 T68 C3 P35794 REQ /replication {wt=javabin&version=2&qt=/replication&command=indexversion} status=0 QTime=2
[junit4:junit4] 2> 23876 T110 C1 P49232 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4] 2> 23877 T110 C1 P49232 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4] 2> 23877 T110 C1 P49232 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4] 2> 23881 T67 C3 P35794 REQ /replication {generation=2&wt=javabin&version=2&qt=/replication&command=filelist} status=0 QTime=1
[junit4:junit4] 2> 23882 T110 C1 P49232 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4] 2> 23885 T110 C1 P49232 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363505854839/jetty4/index.20130317133758716 forceNew: false
[junit4:junit4] 2> 23885 T110 C1 P49232 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@84f637 lockFactory=org.apache.lucene.store.NativeFSLockFactory@f6f36b) fullCopy=false
[junit4:junit4] 2> 23900 T70 C3 P35794 REQ /replication {file=segments_2&checksum=true&generation=2&wt=filestream&qt=/replication&command=filecontent} status=0 QTime=0
[junit4:junit4] 2> 23903 T110 C1 P49232 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4] 2> 23905 T110 C1 P49232 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4] 2> 23906 T110 C1 P49232 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4] 2> 23910 T110 C1 P49232 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@18929dc lockFactory=org.apache.lucene.store.NativeFSLockFactory@145e60d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@18929dc lockFactory=org.apache.lucene.store.NativeFSLockFactory@145e60d),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4] 2> 23912 T110 C1 P49232 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4] 2> 23912 T110 C1 P49232 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4] 2> 23913 T110 C1 P49232 oass.SolrIndexSearcher.<init> Opening Searcher@19cf6ef main
[junit4:junit4] 2> 23916 T109 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19cf6ef main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4] 2> 23917 T110 C1 P49232 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@84f637 lockFactory=org.apache.lucene.store.NativeFSLockFactory@f6f36b)
[junit4:junit4] 2> 23918 T110 C1 P49232 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4] 2> 23919 T110 C1 P49232 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4] 2> 23919 T110 C1 P49232 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 23920 T110 C1 P49232 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 23922 T110 C1 P49232 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4] 2> 24385 T15 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 24852 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 24854 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:32930__onenodecollectioncore",
[junit4:junit4] 2> "collection":"onenodecollection",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:32930",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "node_name":"127.0.0.1:32930_",
[junit4:junit4] 2> "roles":"none",
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "core":"onenodecollectioncore"}
[junit4:junit4] 2> 24860 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:49232__collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49232",
[junit4:junit4] 2> "shard":"shard2",
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "node_name":"127.0.0.1:49232_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"2",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 24866 T90 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 24866 T38 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 24866 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 24866 T107 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 24866 T45 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 24866 T58 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 25388 T15 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 25390 T15 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: onenodecollection
[junit4:junit4] 2> 25391 T15 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 25418 T15 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36228 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f8b332
[junit4:junit4] 2> 25419 T117 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36228. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 25420 T117 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36228, initiating session
[junit4:junit4] 2> 25420 T17 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:43581
[junit4:junit4] 2> 25420 T17 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:43581
[junit4:junit4] 2> 25421 T15 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 25421 T19 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d774839cf000d with negotiated timeout 10000 for client /127.0.0.1:43581
[junit4:junit4] 2> 25421 T117 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36228, sessionid = 0x13d774839cf000d, negotiated timeout = 10000
[junit4:junit4] 2> 25422 T118 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f8b332 name:ZooKeeperConnection Watcher:127.0.0.1:36228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 25422 T15 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 25458 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf000d
[junit4:junit4] 2> 25459 T118 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 25459 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43581 which had sessionid 0x13d774839cf000d
[junit4:junit4] 2> 25459 T15 oaz.ZooKeeper.close Session: 0x13d774839cf000d closed
[junit4:junit4] 2> 25460 T20 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d774839cf0004
[junit4:junit4] 2> 25461 T45 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 25461 T15 oaz.ZooKeeper.close Session: 0x13d774839cf0004 closed
[junit4:junit4] 2> 25461 T17 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:43540 which had sessionid 0x13d774839cf0004
[junit4:junit4] 2> 25464 T15 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 58385
[junit4:junit4] 2> 25464 T15 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=5079265
[junit4:junit4] 2> 26376 T39 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 26378 T39 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4] 2> "core_node_name":"127.0.0.1:58385__collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:58385",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "node_name":"127.0.0.1:58385_",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "core":"collection1"}
[junit4:junit4] 2> 26461 T58 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 26461 T107 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 26461 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 26461 T90 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 26461 T38 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4] 2> 28466 T15 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 28467 T15 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 28472 T15 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache
[...truncated too long message...]
"router":"compositeId",
[junit4:junit4] 1> "shards":{"shard1":{
[junit4:junit4] 1> "range":"80000000-7fffffff",
[junit4:junit4] 1> "state":"active",
[junit4:junit4] 1> "replicas":{"127.0.0.1:32930__onenodecollectioncore":{
[junit4:junit4] 1> "collection":"onenodecollection",
[junit4:junit4] 1> "base_url":"http://127.0.0.1:32930",
[junit4:junit4] 1> "shard":"shard1",
[junit4:junit4] 1> "state":"active",
[junit4:junit4] 1> "node_name":"127.0.0.1:32930_",
[junit4:junit4] 1> "roles":"none",
[junit4:junit4] 1> "core":"onenodecollectioncore",
[junit4:junit4] 1> "leader":"true"}}}}},
[junit4:junit4] 1> "control_collection":{
[junit4:junit4] 1> "router":"compositeId",
[junit4:junit4] 1> "shards":{"shard1":{
[junit4:junit4] 1> "range":"80000000-7fffffff",
[junit4:junit4] 1> "state":"active",
[junit4:junit4] 1> "replicas":{"127.0.0.1:58385__collection1":{
[junit4:junit4] 1> "collection":"control_collection",
[junit4:junit4] 1> "base_url":"http://127.0.0.1:58385",
[junit4:junit4] 1> "shard":"shard1",
[junit4:junit4] 1> "state":"active",
[junit4:junit4] 1> "node_name":"127.0.0.1:58385_",
[junit4:junit4] 1> "core":"collection1",
[junit4:junit4] 1> "leader":"true"}}}}}}
[junit4:junit4] 1>
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicDistributedZk2Test -Dtests.method=testDistribSearch -Dtests.seed=897655F530CDF33A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=th_TH -Dtests.timezone=BST -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR 35.1s J1 | BasicDistributedZk2Test.testDistribSearch <<<
[junit4:junit4] > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:35794/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([897655F530CDF33A:890DBED47929306]:0)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4:junit4] > at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
[junit4:junit4] > at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
[junit4:junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
[junit4:junit4] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4] > at java.lang.reflect.Method.invoke(Method.java:487)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4] > at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4] > at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4] > at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4] > at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4] > at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4] > at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4] > at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4] > at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4] > at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4] > at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4] > at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2> 35131 T15 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> 35135 T14 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4:junit4] 2> 35167 T18 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4] 2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=th_TH, timezone=BST
[junit4:junit4] 2> NOTE: Linux 3.2.0-38-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=2,free=18333248,total=61865984
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestSolrJ, NotRequiredUniqueKeyTest, BasicDistributedZk2Test]
[junit4:junit4] Completed on J1 in 35.42s, 1 test, 1 error <<< FAILURES!
[...truncated 818 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: 272 suites, 1144 tests, 1 error, 13 ignored (7 assumptions)
Total time: 43 minutes 19 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