Return-Path: X-Original-To: apmail-lucene-dev-archive@www.apache.org Delivered-To: apmail-lucene-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 9F649E28F for ; Wed, 20 Feb 2013 01:59:00 +0000 (UTC) Received: (qmail 77282 invoked by uid 500); 20 Feb 2013 01:58:59 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 77218 invoked by uid 500); 20 Feb 2013 01:58:59 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 77210 invoked by uid 99); 20 Feb 2013 01:58:58 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 20 Feb 2013 01:58:58 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=NORMAL_HTTP_TO_IP,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of jenkins@thetaphi.de designates 188.138.97.18 as permitted sender) Received: from [188.138.97.18] (HELO mail.sd-datasolutions.de) (188.138.97.18) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 20 Feb 2013 01:58:36 +0000 Received: from serv1 (localhost.localdomain [127.0.0.1]) by mail.sd-datasolutions.de (Postfix) with ESMTP id 4291214AA053 for ; Wed, 20 Feb 2013 01:58:15 +0000 (UTC) Date: Wed, 20 Feb 2013 01:57:36 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1630312807.17.1361325495242.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b65) - Build # 4392 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_16_1315694434.1361325456830" X-Jenkins-Job: Lucene-Solr-trunk-Linux X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_16_1315694434.1361325456830 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/4392/ Java: 32bit/jdk1.8.0-ea-b65 -client -XX:+UseG1GC 1 tests failed. REGRESSION: org.apache.solr.cloud.UnloadDistributedZkTest.testDistribSearc= h Error Message: Still found shard Stack Trace: java.lang.AssertionError: Still found shard =09at __randomizedtesting.SeedInfo.seed([49F81467FE5CAACA:C81E9A7F8903CAF6]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.UnloadDistributedZkTest.testUnloadShardAndColle= ction(UnloadDistributedZkTest.java:120) =09at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistribute= dZkTest.java:73) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:794) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:474) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at java.lang.Thread.run(Thread.java:722) Build Log: [...truncated 8854 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest [junit4:junit4] 2> 0 T77 oas.BaseDistributedSearchTestCase.initHostContex= t Setting hostContext system property: / [junit4:junit4] 2> 4 T77 oas.SolrTestCaseJ4.setUp ###Starting testDistrib= Search [junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-So= lr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkT= est-1361324598315 [junit4:junit4] 2> 7 T77 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 11 T78 oasc.ZkTestServer$ZKServerMain.runFromConfig St= arting server [junit4:junit4] 2> 30 T78 oaz.Environment.logEnv Server environment:zooke= eper.version=3D3.4.5-1392090, built on 09/30/2012 17:52 GMT [junit4:junit4] 2> 31 T78 oaz.Environment.logEnv Server environment:host.= name=3Dserv1 [junit4:junit4] 2> 31 T78 oaz.Environment.logEnv Server environment:java.= version=3D1.8.0-ea [junit4:junit4] 2> 31 T78 oaz.Environment.logEnv Server environment:java.= vendor=3DOracle Corporation [junit4:junit4] 2> 31 T78 oaz.Environment.logEnv Server environment:java.= home=3D/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b65/jre [junit4:junit4] 2> 31 T78 oaz.Environment.logEnv Server environment:java.= class.path=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucen= e-Solr-trunk-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspa= ce/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ss= d/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/jav= a:/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/l= ucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/s= sd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji= /lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Luce= ne-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phoneti= c-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucen= e/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPS= HOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/mis= c/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/jenkin= s/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0= -SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/bui= ld/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Luc= ene-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/Lu= cene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ss= d/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileuploa= d-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/li= b/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/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/wor= kspace/Lucene-Solr-trunk-Linux/solr/core/lib/objenesis-1.2.jar:/mnt/ssd/jen= kins/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/htt= pclient-4.2.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/s= olrj/lib/httpcore-4.2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/solrj/lib/httpmime-4.2.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Sol= r-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/work= space/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.j= ar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-a= sl-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/wo= rkspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.8.v201211= 06.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/works= pace/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-L= inux/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/mnt/ssd/jenkins/wor= kspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.8.v201211= 06.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/w= orkspace/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/se= rvlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/e= xample/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/mnt/ssd/jenkins/workspac= e/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/cor= e/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/te= st-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trun= k-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.8.jar:/var/l= ib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launche= r.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/jenk= ins/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/l= ib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.An= t_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/to= ols/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/li= b/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_AntInst= allation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.An= t_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/jenkin= s/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-jd= epend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2= /lib/ant-netrexx.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallatio= n/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/hudson.tasks.A= nt_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/huds= on.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/h= udson.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-b65/lib/tools.jar:/var/lib/je= nkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4= -ant-2.0.8.jar [junit4:junit4] 2> 32 T78 oaz.Environment.logEnv Server environment:java.= library.path=3D/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> 32 T78 oaz.Environment.logEnv Server environment:java.= io.tmpdir=3D. [junit4:junit4] 2> 32 T78 oaz.Environment.logEnv Server environment:java.= compiler=3D [junit4:junit4] 2> 33 T78 oaz.Environment.logEnv Server environment:os.na= me=3DLinux [junit4:junit4] 2> 33 T78 oaz.Environment.logEnv Server environment:os.ar= ch=3Di386 [junit4:junit4] 2> 33 T78 oaz.Environment.logEnv Server environment:os.ve= rsion=3D3.2.0-37-generic [junit4:junit4] 2> 33 T78 oaz.Environment.logEnv Server environment:user.= name=3Djenkins [junit4:junit4] 2> 33 T78 oaz.Environment.logEnv Server environment:user.= home=3D/var/lib/jenkins [junit4:junit4] 2> 33 T78 oaz.Environment.logEnv Server environment:user.= dir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0 [junit4:junit4] 2> 45 T78 oazs.ZooKeeperServer.setTickTime tickTime set t= o 1000 [junit4:junit4] 2> 45 T78 oazs.ZooKeeperServer.setMinSessionTimeout minSe= ssionTimeout set to -1 [junit4:junit4] 2> 46 T78 oazs.ZooKeeperServer.setMaxSessionTimeout maxSe= ssionTimeout set to -1 [junit4:junit4] 2> 51 T78 oazs.NIOServerCnxnFactory.configure binding to = port 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 75 T78 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to = /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598315/zookeepe= r/server1/data/version-2/snapshot.0 [junit4:junit4] 2> 110 T77 oasc.ZkTestServer.run start zk server on port:= 48612 [junit4:junit4] 2> 135 T77 oaz.Environment.logEnv Client environment:zook= eeper.version=3D3.4.5-1392090, built on 09/30/2012 17:52 GMT [junit4:junit4] 2> 135 T77 oaz.Environment.logEnv Client environment:host= .name=3Dserv1 [junit4:junit4] 2> 136 T77 oaz.Environment.logEnv Client environment:java= .version=3D1.8.0-ea [junit4:junit4] 2> 136 T77 oaz.Environment.logEnv Client environment:java= .vendor=3DOracle Corporation [junit4:junit4] 2> 136 T77 oaz.Environment.logEnv Client environment:java= .home=3D/var/lib/jenkins/tools/java/32bit/jdk1.8.0-ea-b65/jre [junit4:junit4] 2> 136 T77 oaz.Environment.logEnv Client environment:java= .class.path=3D/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/Luce= ne-Solr-trunk-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/worksp= ace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/s= sd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/ja= va:/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/kuromoj= i/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Luc= ene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonet= ic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/luce= ne/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/w= orkspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAP= SHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/mi= sc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trun= k-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenki= ns/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.= 0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/bu= ild/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lu= cene-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/L= ucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/w= orkspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/s= sd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileuplo= ad-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/l= ib/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-Sol= r-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/core/lib/objenesis-1.2.jar:/mnt/ssd/je= nkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mn= t/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/ht= tpclient-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-L= inux/solr/solrj/lib/httpmime-4.2.3.jar:/mnt/ssd/jenkins/workspace/Lucene-So= lr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/wor= kspace/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/w= orkspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.8.v20121= 106.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-trun= k-Linux/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/mnt/ssd/jenkins/work= space/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.8.v20121106.ja= r:/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/wo= rkspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.8.v20121= 106.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-trun= k-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.v2012110= 6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/s= ervlet-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/workspa= ce/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/co= re/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/t= est-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-tru= nk-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-launch= er.jar:/var/lib/jenkins/.ant/lib/ivy-2.3.0.jar:/var/lib/jenkins/tools/hudso= n.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tool= s/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jen= kins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.ja= r:/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_AntInstal= lation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.A= nt_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/t= ools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/l= ib/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_AntIns= tallation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.A= nt_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/jenki= ns/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-j= depend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.= 2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallati= on/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/hud= son.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-b65/lib/tools.jar:/var/lib/j= enkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit= 4-ant-2.0.8.jar [junit4:junit4] 2> 138 T77 oaz.Environment.logEnv Client environment:java= .library.path=3D/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> 138 T77 oaz.Environment.logEnv Client environment:java= .io.tmpdir=3D. [junit4:junit4] 2> 138 T77 oaz.Environment.logEnv Client environment:java= .compiler=3D [junit4:junit4] 2> 138 T77 oaz.Environment.logEnv Client environment:os.n= ame=3DLinux [junit4:junit4] 2> 139 T77 oaz.Environment.logEnv Client environment:os.a= rch=3Di386 [junit4:junit4] 2> 139 T77 oaz.Environment.logEnv Client environment:os.v= ersion=3D3.2.0-37-generic [junit4:junit4] 2> 139 T77 oaz.Environment.logEnv Client environment:user= .name=3Djenkins [junit4:junit4] 2> 139 T77 oaz.Environment.logEnv Client environment:user= .home=3D/var/lib/jenkins [junit4:junit4] 2> 140 T77 oaz.Environment.logEnv Client environment:user= .dir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-c= ore/test/J0 [junit4:junit4] 2> 141 T77 oaz.ZooKeeper. Initiating client connect= ion, connectString=3D127.0.0.1:48612 sessionTimeout=3D10000 watcher=3Dorg.a= pache.solr.common.cloud.ConnectionManager@1bfaf86 [junit4:junit4] 2> 163 T77 oascc.ConnectionManager.waitForConnected Waiti= ng for client to connect to ZooKeeper [junit4:junit4] 2> 166 T83 oaz.ClientCnxn$SendThread.logStartConnect Open= ing socket connection to server localhost.localdomain/127.0.0.1:48612. Will= not attempt to authenticate using SASL (access denied ("javax.security.aut= h.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 168 T83 oaz.ClientCnxn$SendThread.primeConnection Sock= et connection established to localhost.localdomain/127.0.0.1:48612, initiat= ing session [junit4:junit4] 2> 778 T79 oazs.NIOServerCnxnFactory.run Accepted socket = connection from /127.0.0.1:50934 [junit4:junit4] 2> 787 T79 oazs.ZooKeeperServer.processConnectRequest Cli= ent attempting to establish new session at /127.0.0.1:50934 [junit4:junit4] 2> 791 T81 oazsp.FileTxnLog.append Creating new log file:= log.1 [junit4:junit4] 2> 810 T81 oazs.ZooKeeperServer.finishSessionInit Establi= shed session 0x13cf544e47d0000 with negotiated timeout 10000 for client /12= 7.0.0.1:50934 [junit4:junit4] 2> 810 T83 oaz.ClientCnxn$SendThread.onConnected Session = establishment complete on server localhost.localdomain/127.0.0.1:48612, ses= sionid =3D 0x13cf544e47d0000, negotiated timeout =3D 10000 [junit4:junit4] 2> 813 T84 oascc.ConnectionManager.process Watcher org.ap= ache.solr.common.cloud.ConnectionManager@1bfaf86 name:ZooKeeperConnection W= atcher:127.0.0.1:48612 got event WatchedEvent state:SyncConnected type:None= path:null path:null type:None [junit4:junit4] 2> 813 T77 oascc.ConnectionManager.waitForConnected Clien= t is connected to ZooKeeper [junit4:junit4] 2> 815 T77 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 837 T82 oazs.PrepRequestProcessor.pRequest2Txn Process= ed session termination for sessionid: 0x13cf544e47d0000 [junit4:junit4] 2> 837 T84 oaz.ClientCnxn$EventThread.run EventThread shu= t down [junit4:junit4] 2> 837 T77 oaz.ZooKeeper.close Session: 0x13cf544e47d0000= closed [junit4:junit4] 2> 838 T77 oaz.ZooKeeper. Initiating client connect= ion, connectString=3D127.0.0.1:48612/solr sessionTimeout=3D10000 watcher=3D= org.apache.solr.common.cloud.ConnectionManager@1bd8aa6 [junit4:junit4] 2> 838 T79 oazs.NIOServerCnxn.closeSock Closed socket con= nection for client /127.0.0.1:50934 which had sessionid 0x13cf544e47d0000 [junit4:junit4] 2> 839 T77 oascc.ConnectionManager.waitForConnected Waiti= ng for client to connect to ZooKeeper [junit4:junit4] 2> 839 T85 oaz.ClientCnxn$SendThread.logStartConnect Open= ing socket connection to server localhost.localdomain/127.0.0.1:48612. Will= not attempt to authenticate using SASL (access denied ("javax.security.aut= h.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 839 T85 oaz.ClientCnxn$SendThread.primeConnection Sock= et connection established to localhost.localdomain/127.0.0.1:48612, initiat= ing session [junit4:junit4] 2> 839 T79 oazs.NIOServerCnxnFactory.run Accepted socket = connection from /127.0.0.1:50940 [junit4:junit4] 2> 840 T79 oazs.ZooKeeperServer.processConnectRequest Cli= ent attempting to establish new session at /127.0.0.1:50940 [junit4:junit4] 2> 841 T81 oazs.ZooKeeperServer.finishSessionInit Establi= shed session 0x13cf544e47d0001 with negotiated timeout 10000 for client /12= 7.0.0.1:50940 [junit4:junit4] 2> 841 T85 oaz.ClientCnxn$SendThread.onConnected Session = establishment complete on server localhost.localdomain/127.0.0.1:48612, ses= sionid =3D 0x13cf544e47d0001, negotiated timeout =3D 10000 [junit4:junit4] 2> 841 T86 oascc.ConnectionManager.process Watcher org.ap= ache.solr.common.cloud.ConnectionManager@1bd8aa6 name:ZooKeeperConnection W= atcher:127.0.0.1:48612/solr got event WatchedEvent state:SyncConnected type= :None path:null path:null type:None [junit4:junit4] 2> 841 T77 oascc.ConnectionManager.waitForConnected Clien= t is connected to ZooKeeper [junit4:junit4] 2> 843 T77 oascc.SolrZkClient.makePath makePath: /collect= ions/collection1 [junit4:junit4] 2> 846 T77 oascc.SolrZkClient.makePath makePath: /collect= ions/collection1/shards [junit4:junit4] 2> 849 T77 oascc.SolrZkClient.makePath makePath: /collect= ions/control_collection [junit4:junit4] 2> 852 T77 oascc.SolrZkClient.makePath makePath: /collect= ions/control_collection/shards [junit4:junit4] 2> 855 T77 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> 862 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/solrconfig.xml [junit4:junit4] 2> 867 T77 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> 868 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/schema.xml [junit4:junit4] 2> 872 T77 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> 873 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/stopwords.txt [junit4:junit4] 2> 876 T77 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> 876 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/protwords.txt [junit4:junit4] 2> 879 T77 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> 879 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/currency.xml [junit4:junit4] 2> 882 T77 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-excha= nge-rates.json [junit4:junit4] 2> 883 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/open-exchange-rates.json [junit4:junit4] 2> 886 T77 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> 886 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 889 T77 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> 889 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/old_synonyms.txt [junit4:junit4] 2> 892 T77 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> 892 T77 oascc.SolrZkClient.makePath makePath: /configs= /conf1/synonyms.txt [junit4:junit4] 2> 895 T82 oazs.PrepRequestProcessor.pRequest2Txn Process= ed session termination for sessionid: 0x13cf544e47d0001 [junit4:junit4] 2> 896 T86 oaz.ClientCnxn$EventThread.run EventThread shu= t down [junit4:junit4] 2> 896 T77 oaz.ZooKeeper.close Session: 0x13cf544e47d0001= closed [junit4:junit4] 2> 896 T79 oazs.NIOServerCnxn.closeSock Closed socket con= nection for client /127.0.0.1:50940 which had sessionid 0x13cf544e47d0001 [junit4:junit4] 2> 1073 T77 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 1152 T77 oejs.AbstractConnector.doStart Started Select= ChannelConnector@127.0.0.1:52900 [junit4:junit4] 2> 1164 T77 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() [junit4:junit4] 2> 1165 T77 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1165 T77 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribu= tedZkTest-controljetty-1361324599207 [junit4:junit4] 2> 1165 T77 oasc.CoreContainer$Initializer.initialize loo= king for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-con= troljetty-1361324599207/solr.xml [junit4:junit4] 2> 1166 T77 oasc.CoreContainer. New CoreContainer 1= 09640 [junit4:junit4] 2> 1166 T77 oasc.CoreContainer.load Loading CoreContainer= using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-con= troljetty-1361324599207/' [junit4:junit4] 2> 1166 T77 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux= /solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTe= st-controljetty-1361324599207/' [junit4:junit4] 2> 1199 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 120000 [junit4:junit4] 2> 1200 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to: http:// [junit4:junit4] 2> 1200 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 15000 [junit4:junit4] 2> 1200 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1201 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4:junit4] 2> 1201 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1201 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1202 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4:junit4] 2> 1202 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4:junit4] 2> 1214 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&so= cketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 1385 T77 oasc.CoreContainer.load Registering Log Liste= ner [junit4:junit4] 2> 1399 T77 oasc.CoreContainer.initZooKeeper Zookeeper cl= ient=3D127.0.0.1:48612/solr [junit4:junit4] 2> 1407 T77 oasc.ZkController.checkChrootPath zkHost incl= udes chroot [junit4:junit4] 2> 1407 T77 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:48612 sessionTimeout=3D60000 watcher=3Dorg.= apache.solr.common.cloud.ConnectionManager@ee3a05 [junit4:junit4] 2> 1408 T77 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 1409 T97 oaz.ClientCnxn$SendThread.logStartConnect Ope= ning socket connection to server localhost.localdomain/127.0.0.1:48612. Wil= l not attempt to authenticate using SASL (access denied ("javax.security.au= th.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 1409 T97 oaz.ClientCnxn$SendThread.primeConnection Soc= ket connection established to localhost.localdomain/127.0.0.1:48612, initia= ting session [junit4:junit4] 2> 1410 T79 oazs.NIOServerCnxnFactory.run Accepted socket= connection from /127.0.0.1:50941 [junit4:junit4] 2> 1410 T79 oazs.ZooKeeperServer.processConnectRequest Cl= ient attempting to establish new session at /127.0.0.1:50941 [junit4:junit4] 2> 1411 T81 oazs.ZooKeeperServer.finishSessionInit Establ= ished session 0x13cf544e47d0002 with negotiated timeout 20000 for client /1= 27.0.0.1:50941 [junit4:junit4] 2> 1411 T97 oaz.ClientCnxn$SendThread.onConnected Session= establishment complete on server localhost.localdomain/127.0.0.1:48612, se= ssionid =3D 0x13cf544e47d0002, negotiated timeout =3D 20000 [junit4:junit4] 2> 1412 T98 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@ee3a05 name:ZooKeeperConnection W= atcher:127.0.0.1:48612 got event WatchedEvent state:SyncConnected type:None= path:null path:null type:None [junit4:junit4] 2> 1412 T77 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 1413 T82 oazs.PrepRequestProcessor.pRequest2Txn Proces= sed session termination for sessionid: 0x13cf544e47d0002 [junit4:junit4] 2> 1414 T98 oaz.ClientCnxn$EventThread.run EventThread sh= ut down [junit4:junit4] 2> 1414 T79 oazs.NIOServerCnxn.closeSock Closed socket co= nnection for client /127.0.0.1:50941 which had sessionid 0x13cf544e47d0002 [junit4:junit4] 2> 1414 T77 oaz.ZooKeeper.close Session: 0x13cf544e47d000= 2 closed [junit4:junit4] 2> 1416 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&sock= etTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 1420 T77 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:48612/solr sessionTimeout=3D30000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1f830e0 [junit4:junit4] 2> 1421 T99 oaz.ClientCnxn$SendThread.logStartConnect Ope= ning socket connection to server localhost.localdomain/127.0.0.1:48612. Wil= l not attempt to authenticate using SASL (access denied ("javax.security.au= th.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 1421 T77 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 1421 T99 oaz.ClientCnxn$SendThread.primeConnection Soc= ket connection established to localhost.localdomain/127.0.0.1:48612, initia= ting session [junit4:junit4] 2> 1422 T79 oazs.NIOServerCnxnFactory.run Accepted socket= connection from /127.0.0.1:50942 [junit4:junit4] 2> 1422 T79 oazs.ZooKeeperServer.processConnectRequest Cl= ient attempting to establish new session at /127.0.0.1:50942 [junit4:junit4] 2> 1423 T81 oazs.ZooKeeperServer.finishSessionInit Establ= ished session 0x13cf544e47d0003 with negotiated timeout 20000 for client /1= 27.0.0.1:50942 [junit4:junit4] 2> 1423 T99 oaz.ClientCnxn$SendThread.onConnected Session= establishment complete on server localhost.localdomain/127.0.0.1:48612, se= ssionid =3D 0x13cf544e47d0003, negotiated timeout =3D 20000 [junit4:junit4] 2> 1423 T100 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1f830e0 name:ZooKeeperConnection= Watcher:127.0.0.1:48612/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 1423 T77 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 1429 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error= :KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1433 T77 oascc.SolrZkClient.makePath makePath: /live_n= odes [junit4:junit4] 2> 1435 T77 oasc.ZkController.createEphemeralLiveNode Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:52900_ [junit4:junit4] 2> 1437 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:delet= e cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127= .0.0.1:52900_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.0.0= .1:52900_ [junit4:junit4] 2> 1438 T77 oascc.SolrZkClient.makePath makePath: /live_n= odes/127.0.0.1:52900_ [junit4:junit4] 2> 1441 T77 oascc.SolrZkClient.makePath makePath: /overse= er_elect/election [junit4:junit4] 2> 1450 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:delet= e cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elec= t/leader Error:KeeperErrorCode =3D NoNode for /solr/overseer_elect/leader [junit4:junit4] 2> 1450 T77 oascc.SolrZkClient.makePath makePath: /overse= er_elect/leader [junit4:junit4] 2> 1453 T77 oasc.Overseer.start Overseer (id=3D8921576888= 0545795-127.0.0.1:52900_-n_0000000000) starting [junit4:junit4] 2> 1453 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1460 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1461 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1465 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1466 T102 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 1467 T77 oascc.SolrZkClient.makePath makePath: /cluste= rstate.json [junit4:junit4] 2> 1469 T77 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1476 T101 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 1490 T103 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-tru= nk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistrib= utedZkTest-controljetty-1361324599207/collection1 [junit4:junit4] 2> 1490 T103 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4:junit4] 2> 1491 T103 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 1491 T103 oasc.ZkController.readConfigName Load collec= tion config from:/collections/control_collection [junit4:junit4] 2> 1493 T103 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-controljetty-1361324599207/collection1/' [junit4:junit4] 2> 1493 T103 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-controljetty= -1361324599207/collection1/lib/README' to classloader [junit4:junit4] 2> 1494 T103 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-controljetty= -1361324599207/collection1/lib/classes/' to classloader [junit4:junit4] 2> 1529 T103 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 1576 T103 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 1578 T103 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 1586 T103 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 2005 T103 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 2012 T103 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 2014 T103 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 2028 T103 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 2032 T103 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 2047 T103 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2049 T103 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2050 T103 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-control= jetty-1361324599207/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadD= istributedZkTest-1361324598315/control/data/ [junit4:junit4] 2> 2051 T103 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@417ee9 [junit4:junit4] 2> 2053 T103 oasc.SolrCore.initDirectoryFactory org.apach= e.solr.core.MockDirectoryFactory [junit4:junit4] 2> 2054 T103 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245= 98315/control/data forceNew:false [junit4:junit4] 2> 2054 T103 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/control/data [junit4:junit4] 2> 2054 T103 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributedZk= Test-1361324598315/control/data/index/ [junit4:junit4] 2> 2054 T103 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-136= 1324598315/control/data/index' doesn't exist. Creating new index... [junit4:junit4] 2> 2055 T103 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245= 98315/control/data/index forceNew:false [junit4:junit4] 2> 2057 T103 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@602f47 lockFactory=3Dorg.apach= e.lucene.store.NativeFSLockFactory@12ff092)),segFN=3Dsegments_1,generation= =3D1,filenames=3D[segments_1] [junit4:junit4] 2> 2057 T103 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1[segments_1] [junit4:junit4] 2> 2057 T103 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/control/data/index [junit4:junit4] 2> 2058 T103 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 2058 T103 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 2059 T103 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 2059 T103 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 2060 T103 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 2060 T103 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 2060 T103 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 2060 T103 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 2061 T103 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 2063 T103 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 2065 T103 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/control/data [junit4:junit4] 2> 2066 T103 oass.SolrIndexSearcher. Opening Search= er@b7a276 main [junit4:junit4] 2> 2066 T103 oasu.UpdateHandler.clearLog Clearing tlog fi= les, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-136132459831= 5/control/data/tlog [junit4:junit4] 2> 2066 T103 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 2066 T103 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 2069 T103 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/control/data [junit4:junit4] 2> 2070 T104 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@b7a276 main{StandardDirectoryReader(segme= nts_1:1)} [junit4:junit4] 2> 2070 T103 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown [junit4:junit4] 2> 2070 T103 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4:junit4] 2> 2071 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x50 zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queu= e Error:KeeperErrorCode =3D NoNode for /solr/overseer/queue [junit4:junit4] 2> 2981 T101 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2982 T101 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:52900", [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "collection":"control_collection", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:52900_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 2983 T101 oasc.Overseer$ClusterStateUpdater.createColl= ection Create collection control_collection with numShards 1 [junit4:junit4] 2> 2989 T101 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 2990 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x58 zxid:0x2c txntype:-1 reqpath:n/a Error Path:/solr/overseer/queu= e-work Error:KeeperErrorCode =3D NoNode for /solr/overseer/queue-work [junit4:junit4] 2> 3002 T100 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4:junit4] 2> 3074 T103 oasc.CoreContainer.registerCore registering = core: collection1 [junit4:junit4] 2> 3074 T103 oasc.ZkController.register Register replica = - core:collection1 address:http://127.0.0.1:52900 collection:control_collec= tion shard:shard1 [junit4:junit4] 2> 3078 T103 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D10000&maxConnectionsPerHost=3D20&c= onnTimeout=3D30000&socketTimeout=3D30000&retry=3Dfalse [junit4:junit4] 2> 3083 T103 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/leader_elect/shard1/election [junit4:junit4] 2> 3091 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:delet= e cxid:0x72 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections/c= ontrol_collection/leaders Error:KeeperErrorCode =3D NoNode for /solr/collec= tions/control_collection/leaders [junit4:junit4] 2> 3092 T103 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process. [junit4:junit4] 2> 3092 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3094 T103 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4:junit4] 2> 3094 T103 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4:junit4] 2> 3095 T103 oasc.SyncStrategy.sync Sync replicas to http= ://127.0.0.1:52900/collection1/ [junit4:junit4] 2> 3095 T103 oasc.SyncStrategy.syncReplicas Sync Success = - now sync replicas to me [junit4:junit4] 2> 3095 T103 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 52900/collection1/ has no replicas [junit4:junit4] 2> 3095 T103 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:52900/collection1/ [junit4:junit4] 2> 3095 T103 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/leaders/shard1 [junit4:junit4] 2> 3100 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0003 type:creat= e cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4507 T101 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4516 T100 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4:junit4] 2> 4555 T103 oasc.ZkController.register We are http://127= .0.0.1:52900/collection1/ and leader is http://127.0.0.1:52900/collection1/ [junit4:junit4] 2> 4555 T103 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:52900 [junit4:junit4] 2> 4556 T103 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4:junit4] 2> 4556 T103 oasc.ZkController.publish publishing core=3D= collection1 state=3Dactive [junit4:junit4] 2> 4556 T103 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4:junit4] 2> 4557 T103 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4558 T77 oass.SolrDispatchFilter.init user.dir=3D/mnt/= ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 4559 T77 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() done [junit4:junit4] 2> 4572 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&foll= owRedirects=3Dfalse [junit4:junit4] 2> 4578 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config: [junit4:junit4] 2> 4579 T77 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:48612/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@e418b0 [junit4:junit4] 2> 4580 T105 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:48612. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 4580 T77 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 4581 T105 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:48612, initi= ating session [junit4:junit4] 2> 4581 T79 oazs.NIOServerCnxnFactory.run Accepted socket= connection from /127.0.0.1:50945 [junit4:junit4] 2> 4581 T79 oazs.ZooKeeperServer.processConnectRequest Cl= ient attempting to establish new session at /127.0.0.1:50945 [junit4:junit4] 2> 4582 T81 oazs.ZooKeeperServer.finishSessionInit Establ= ished session 0x13cf544e47d0004 with negotiated timeout 10000 for client /1= 27.0.0.1:50945 [junit4:junit4] 2> 4582 T105 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:48612, s= essionid =3D 0x13cf544e47d0004, negotiated timeout =3D 10000 [junit4:junit4] 2> 4582 T106 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@e418b0 name:ZooKeeperConnection = Watcher:127.0.0.1:48612/solr got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 4582 T77 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 4583 T77 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 4586 T77 oasc.ChaosMonkey.monkeyLog monkey: init - exp= ire sessions:true cause connection loss:true [junit4:junit4] 2> 4640 T77 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 4643 T77 oejs.AbstractConnector.doStart Started Select= ChannelConnector@127.0.0.1:57896 [junit4:junit4] 2> 4643 T77 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() [junit4:junit4] 2> 4643 T77 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4644 T77 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribu= tedZkTest-jetty1-1361324602897 [junit4:junit4] 2> 4644 T77 oasc.CoreContainer$Initializer.initialize loo= king for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty1-1361324602897/solr.xml [junit4:junit4] 2> 4644 T77 oasc.CoreContainer. New CoreContainer 2= 7536497 [junit4:junit4] 2> 4645 T77 oasc.CoreContainer.load Loading CoreContainer= using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty1-1361324602897/' [junit4:junit4] 2> 4645 T77 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux= /solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTe= st-jetty1-1361324602897/' [junit4:junit4] 2> 4662 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 120000 [junit4:junit4] 2> 4662 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to: http:// [junit4:junit4] 2> 4662 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 15000 [junit4:junit4] 2> 4663 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 4663 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4:junit4] 2> 4663 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 4663 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4:junit4] 2> 4664 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4:junit4] 2> 4664 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4:junit4] 2> 4664 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&so= cketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 4672 T77 oasc.CoreContainer.load Registering Log Liste= ner [junit4:junit4] 2> 4684 T77 oasc.CoreContainer.initZooKeeper Zookeeper cl= ient=3D127.0.0.1:48612/solr [junit4:junit4] 2> 4684 T77 oasc.ZkController.checkChrootPath zkHost incl= udes chroot [junit4:junit4] 2> 4684 T77 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:48612 sessionTimeout=3D60000 watcher=3Dorg.= apache.solr.common.cloud.ConnectionManager@167309a [junit4:junit4] 2> 4685 T77 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 4685 T116 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:48612. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 4686 T116 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:48612, initi= ating session [junit4:junit4] 2> 4686 T79 oazs.NIOServerCnxnFactory.run Accepted socket= connection from /127.0.0.1:50946 [junit4:junit4] 2> 4686 T79 oazs.ZooKeeperServer.processConnectRequest Cl= ient attempting to establish new session at /127.0.0.1:50946 [junit4:junit4] 2> 4687 T81 oazs.ZooKeeperServer.finishSessionInit Establ= ished session 0x13cf544e47d0005 with negotiated timeout 20000 for client /1= 27.0.0.1:50946 [junit4:junit4] 2> 4687 T116 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:48612, s= essionid =3D 0x13cf544e47d0005, negotiated timeout =3D 20000 [junit4:junit4] 2> 4688 T117 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@167309a name:ZooKeeperConnection= Watcher:127.0.0.1:48612 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4:junit4] 2> 4688 T77 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 4689 T82 oazs.PrepRequestProcessor.pRequest2Txn Proces= sed session termination for sessionid: 0x13cf544e47d0005 [junit4:junit4] 2> 4690 T117 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 4690 T79 oazs.NIOServerCnxn.closeSock Closed socket co= nnection for client /127.0.0.1:50946 which had sessionid 0x13cf544e47d0005 [junit4:junit4] 2> 4689 T77 oaz.ZooKeeper.close Session: 0x13cf544e47d000= 5 closed [junit4:junit4] 2> 4690 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&sock= etTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 4693 T77 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:48612/solr sessionTimeout=3D30000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@5f146f [junit4:junit4] 2> 4694 T118 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:48612. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 4694 T77 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 4695 T118 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:48612, initi= ating session [junit4:junit4] 2> 4695 T79 oazs.NIOServerCnxnFactory.run Accepted socket= connection from /127.0.0.1:50947 [junit4:junit4] 2> 4695 T79 oazs.ZooKeeperServer.processConnectRequest Cl= ient attempting to establish new session at /127.0.0.1:50947 [junit4:junit4] 2> 4696 T81 oazs.ZooKeeperServer.finishSessionInit Establ= ished session 0x13cf544e47d0006 with negotiated timeout 20000 for client /1= 27.0.0.1:50947 [junit4:junit4] 2> 4696 T118 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:48612, s= essionid =3D 0x13cf544e47d0006, negotiated timeout =3D 20000 [junit4:junit4] 2> 4697 T119 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@5f146f name:ZooKeeperConnection = Watcher:127.0.0.1:48612/solr got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 4697 T77 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 4698 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0006 type:creat= e cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error= :KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4698 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0006 type:creat= e cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error= :KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4700 T77 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 5702 T77 oasc.ZkController.createEphemeralLiveNode Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:57896_ [junit4:junit4] 2> 5703 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0006 type:delet= e cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127= .0.0.1:57896_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.0.0= .1:57896_ [junit4:junit4] 2> 5704 T77 oascc.SolrZkClient.makePath makePath: /live_n= odes/127.0.0.1:57896_ [junit4:junit4] 2> 5707 T100 oascc.ZkStateReader$3.process Updating live = nodes... (2) [junit4:junit4] 2> 5708 T106 oascc.ZkStateReader$3.process Updating live = nodes... (2) [junit4:junit4] 2> 5708 T100 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/l= ive_nodes, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 5708 T119 oascc.ZkStateReader$3.process Updating live = nodes... (2) [junit4:junit4] 2> 5716 T120 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-tru= nk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistrib= utedZkTest-jetty1-1361324602897/collection1 [junit4:junit4] 2> 5716 T120 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4:junit4] 2> 5717 T120 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 5717 T120 oasc.ZkController.readConfigName Load collec= tion config from:/collections/collection1 [junit4:junit4] 2> 5719 T120 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty1-1361324602897/collection1/' [junit4:junit4] 2> 5719 T120 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-13613= 24602897/collection1/lib/README' to classloader [junit4:junit4] 2> 5719 T120 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-13613= 24602897/collection1/lib/classes/' to classloader [junit4:junit4] 2> 5754 T120 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 5791 T120 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 5792 T120 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 5796 T120 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 6022 T101 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6023 T101 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:52900", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "collection":"control_collection", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:52900_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 6027 T119 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 6027 T106 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 6027 T100 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 6224 T120 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 6230 T120 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 6233 T120 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 6245 T120 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 6248 T120 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 6251 T120 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 6253 T120 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 6254 T120 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-= 1361324602897/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDistrib= utedZkTest-1361324598315/jetty1/ [junit4:junit4] 2> 6254 T120 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@417ee9 [junit4:junit4] 2> 6255 T120 oasc.SolrCore.initDirectoryFactory org.apach= e.solr.core.MockDirectoryFactory [junit4:junit4] 2> 6255 T120 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245= 98315/jetty1 forceNew:false [junit4:junit4] 2> 6256 T120 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/jetty1 [junit4:junit4] 2> 6256 T120 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributedZk= Test-1361324598315/jetty1/index/ [junit4:junit4] 2> 6256 T120 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-136= 1324598315/jetty1/index' doesn't exist. Creating new index... [junit4:junit4] 2> 6257 T120 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245= 98315/jetty1/index forceNew:false [junit4:junit4] 2> 6258 T120 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@bcb45a lockFactory=3Dorg.apach= e.lucene.store.NativeFSLockFactory@c8dd5)),segFN=3Dsegments_1,generation=3D= 1,filenames=3D[segments_1] [junit4:junit4] 2> 6259 T120 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1[segments_1] [junit4:junit4] 2> 6259 T120 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/jetty1/index [junit4:junit4] 2> 6260 T120 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 6260 T120 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 6261 T120 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 6261 T120 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 6262 T120 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 6262 T120 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 6262 T120 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 6263 T120 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 6263 T120 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 6265 T120 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 6268 T120 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/jetty1 [junit4:junit4] 2> 6268 T120 oass.SolrIndexSearcher. Opening Search= er@a6ee83 main [junit4:junit4] 2> 6268 T120 oasu.UpdateHandler.clearLog Clearing tlog fi= les, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-136132459831= 5/jetty1/tlog [junit4:junit4] 2> 6269 T120 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 6269 T120 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 6274 T121 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@a6ee83 main{StandardDirectoryReader(segme= nts_1:1)} [junit4:junit4] 2> 6276 T120 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown [junit4:junit4] 2> 6276 T120 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4:junit4] 2> 7532 T101 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7532 T101 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:57896", [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:57896_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 7533 T101 oasc.Overseer$ClusterStateUpdater.createColl= ection Create collection collection1 with numShards 2 [junit4:junit4] 2> 7533 T101 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard2 [junit4:junit4] 2> 7537 T100 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 7537 T119 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 7537 T106 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 8278 T120 oasc.CoreContainer.registerCore registering = core: collection1 [junit4:junit4] 2> 8278 T120 oasc.ZkController.register Register replica = - core:collection1 address:http://127.0.0.1:57896 collection:collection1 sh= ard:shard2 [junit4:junit4] 2> 8279 T120 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leader_elect/shard2/election [junit4:junit4] 2> 8287 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0006 type:delet= e cxid:0x46 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/c= ollection1/leaders Error:KeeperErrorCode =3D NoNode for /solr/collections/c= ollection1/leaders [junit4:junit4] 2> 8288 T120 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process. [junit4:junit4] 2> 8288 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0006 type:creat= e cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8290 T120 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4:junit4] 2> 8290 T120 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4:junit4] 2> 8291 T120 oasc.SyncStrategy.sync Sync replicas to http= ://127.0.0.1:57896/collection1/ [junit4:junit4] 2> 8291 T120 oasc.SyncStrategy.syncReplicas Sync Success = - now sync replicas to me [junit4:junit4] 2> 8291 T120 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 57896/collection1/ has no replicas [junit4:junit4] 2> 8291 T120 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:57896/collection1/ [junit4:junit4] 2> 8291 T120 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leaders/shard2 [junit4:junit4] 2> 8296 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0006 type:creat= e cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 9042 T101 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9049 T100 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 9049 T106 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 9050 T119 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 9101 T120 oasc.ZkController.register We are http://127= .0.0.1:57896/collection1/ and leader is http://127.0.0.1:57896/collection1/ [junit4:junit4] 2> 9101 T120 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:57896 [junit4:junit4] 2> 9101 T120 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4:junit4] 2> 9101 T120 oasc.ZkController.publish publishing core=3D= collection1 state=3Dactive [junit4:junit4] 2> 9101 T120 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4:junit4] 2> 9103 T120 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9104 T77 oass.SolrDispatchFilter.init user.dir=3D/mnt/= ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 9104 T77 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() done [junit4:junit4] 2> 9105 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&foll= owRedirects=3Dfalse [junit4:junit4] 2> 9172 T77 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 9174 T77 oejs.AbstractConnector.doStart Started Select= ChannelConnector@127.0.0.1:33644 [junit4:junit4] 2> 9174 T77 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() [junit4:junit4] 2> 9175 T77 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 9175 T77 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribu= tedZkTest-jetty2-1361324607422 [junit4:junit4] 2> 9175 T77 oasc.CoreContainer$Initializer.initialize loo= king for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty2-1361324607422/solr.xml [junit4:junit4] 2> 9176 T77 oasc.CoreContainer. New CoreContainer 2= 5616786 [junit4:junit4] 2> 9176 T77 oasc.CoreContainer.load Loading CoreContainer= using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty2-1361324607422/' [junit4:junit4] 2> 9176 T77 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux= /solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTe= st-jetty2-1361324607422/' [junit4:junit4] 2> 9194 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 120000 [junit4:junit4] 2> 9194 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to: http:// [junit4:junit4] 2> 9195 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 15000 [junit4:junit4] 2> 9195 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 9195 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4:junit4] 2> 9196 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 9196 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4:junit4] 2> 9197 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4:junit4] 2> 9197 T77 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4:junit4] 2> 9197 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&so= cketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 9205 T77 oasc.CoreContainer.load Registering Log Liste= ner [junit4:junit4] 2> 9218 T77 oasc.CoreContainer.initZooKeeper Zookeeper cl= ient=3D127.0.0.1:48612/solr [junit4:junit4] 2> 9218 T77 oasc.ZkController.checkChrootPath zkHost incl= udes chroot [junit4:junit4] 2> 9218 T77 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:48612 sessionTimeout=3D60000 watcher=3Dorg.= apache.solr.common.cloud.ConnectionManager@1e28c04 [junit4:junit4] 2> 9219 T77 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 9220 T131 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:48612. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 9220 T131 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:48612, initi= ating session [junit4:junit4] 2> 9220 T79 oazs.NIOServerCnxnFactory.run Accepted socket= connection from /127.0.0.1:50949 [junit4:junit4] 2> 9221 T79 oazs.ZooKeeperServer.processConnectRequest Cl= ient attempting to establish new session at /127.0.0.1:50949 [junit4:junit4] 2> 9222 T81 oazs.ZooKeeperServer.finishSessionInit Establ= ished session 0x13cf544e47d0007 with negotiated timeout 20000 for client /1= 27.0.0.1:50949 [junit4:junit4] 2> 9222 T131 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:48612, s= essionid =3D 0x13cf544e47d0007, negotiated timeout =3D 20000 [junit4:junit4] 2> 9222 T132 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1e28c04 name:ZooKeeperConnection= Watcher:127.0.0.1:48612 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4:junit4] 2> 9222 T77 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 9223 T82 oazs.PrepRequestProcessor.pRequest2Txn Proces= sed session termination for sessionid: 0x13cf544e47d0007 [junit4:junit4] 2> 9224 T132 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 9224 T79 oazs.NIOServerCnxn.closeSock Closed socket co= nnection for client /127.0.0.1:50949 which had sessionid 0x13cf544e47d0007 [junit4:junit4] 2> 9224 T77 oaz.ZooKeeper.close Session: 0x13cf544e47d000= 7 closed [junit4:junit4] 2> 9224 T77 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&sock= etTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 9228 T77 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:48612/solr sessionTimeout=3D30000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1bc23e5 [junit4:junit4] 2> 9229 T77 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 9229 T133 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:48612. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 9236 T133 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:48612, initi= ating session [junit4:junit4] 2> 9236 T79 oazs.NIOServerCnxnFactory.run Accepted socket= connection from /127.0.0.1:50950 [junit4:junit4] 2> 9236 T79 oazs.ZooKeeperServer.processConnectRequest Cl= ient attempting to establish new session at /127.0.0.1:50950 [junit4:junit4] 2> 9237 T81 oazs.ZooKeeperServer.finishSessionInit Establ= ished session 0x13cf544e47d0008 with negotiated timeout 20000 for client /1= 27.0.0.1:50950 [junit4:junit4] 2> 9237 T133 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:48612, s= essionid =3D 0x13cf544e47d0008, negotiated timeout =3D 20000 [junit4:junit4] 2> 9237 T134 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1bc23e5 name:ZooKeeperConnection= Watcher:127.0.0.1:48612/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 9237 T77 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 9238 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0008 type:creat= e cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error= :KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 9239 T82 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x13cf544e47d0008 type:creat= e cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error= :KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 9241 T77 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 10243 T77 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:33644_ [junit4:junit4] 2> 10244 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d0008 type:dele= te cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/12= 7.0.0.1:33644_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.0.= 0.1:33644_ [junit4:junit4] 2> 10244 T77 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:33644_ [junit4:junit4] 2> 10247 T119 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 10247 T100 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 10247 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10247 T106 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 10247 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10247 T134 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 10248 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10254 T135 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-tr= unk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistri= butedZkTest-jetty2-1361324607422/collection1 [junit4:junit4] 2> 10254 T135 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 10255 T135 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 10255 T135 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 10256 T135 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Lin= ux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZk= Test-jetty2-1361324607422/collection1/' [junit4:junit4] 2> 10256 T135 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361= 324607422/collection1/lib/README' to classloader [junit4:junit4] 2> 10257 T135 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361= 324607422/collection1/lib/classes/' to classloader [junit4:junit4] 2> 10284 T135 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 10326 T135 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 10327 T135 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 10331 T135 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 10555 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10555 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:57896", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:57896_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 10559 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10559 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10559 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10559 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10746 T135 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 10752 T135 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 10755 T135 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 10765 T135 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 10770 T135 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 10774 T135 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 10776 T135 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 10777 T135 oasc.SolrCore. [collection1] Opening = new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bui= ld/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2= -1361324607422/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDistri= butedZkTest-1361324598315/jetty2/ [junit4:junit4] 2> 10777 T135 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@417ee9 [junit4:junit4] 2> 10778 T135 oasc.SolrCore.initDirectoryFactory org.apac= he.solr.core.MockDirectoryFactory [junit4:junit4] 2> 10779 T135 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324= 598315/jetty2 forceNew:false [junit4:junit4] 2> 10779 T135 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty2 [junit4:junit4] 2> 10779 T135 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributedZ= kTest-1361324598315/jetty2/index/ [junit4:junit4] 2> 10779 T135 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-13= 61324598315/jetty2/index' doesn't exist. Creating new index... [junit4:junit4] 2> 10780 T135 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324= 598315/jetty2/index forceNew:false [junit4:junit4] 2> 10781 T135 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@16421ed lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@5adb45)),segFN=3Dsegments_1,generation= =3D1,filenames=3D[segments_1] [junit4:junit4] 2> 10781 T135 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 10782 T135 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty2/index [junit4:junit4] 2> 10790 T135 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 10790 T135 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 10791 T135 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 10792 T135 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 10793 T135 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 10793 T135 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 10794 T135 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 10794 T135 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 10795 T135 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 10799 T135 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 10803 T135 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty2 [junit4:junit4] 2> 10804 T135 oass.SolrIndexSearcher. Opening Searc= her@1f8e36 main [junit4:junit4] 2> 10804 T135 oasu.UpdateHandler.clearLog Clearing tlog f= iles, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/jetty2/tlog [junit4:junit4] 2> 10805 T135 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 10806 T135 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 10813 T136 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1f8e36 main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 10816 T135 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Ddown [junit4:junit4] 2> 10816 T135 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 12063 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12064 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:33644", [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:33644_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 12064 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Collection already exists with numShards=3D2 [junit4:junit4] 2> 12064 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 12172 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 12172 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 12172 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 12172 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 12818 T135 oasc.CoreContainer.registerCore registering= core: collection1 [junit4:junit4] 2> 12818 T135 oasc.ZkController.register Register replica= - core:collection1 address:http://127.0.0.1:33644 collection:collection1 s= hard:shard1 [junit4:junit4] 2> 12819 T135 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 12825 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d0008 type:dele= te cxid:0x45 zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/collections/= collection1/leaders/shard1 Error:KeeperErrorCode =3D NoNode for /solr/colle= ctions/collection1/leaders/shard1 [junit4:junit4] 2> 12825 T135 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 12825 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d0008 type:crea= te cxid:0x46 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 12827 T135 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 12827 T135 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 12827 T135 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:33644/collection1/ [junit4:junit4] 2> 12827 T135 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 12828 T135 oasc.SyncStrategy.syncToMe http://127.0.0.1= :33644/collection1/ has no replicas [junit4:junit4] 2> 12828 T135 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:33644/collection1/ [junit4:junit4] 2> 12828 T135 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard1 [junit4:junit4] 2> 12831 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d0008 type:crea= te cxid:0x4f zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 13678 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13687 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 13688 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 13688 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 13688 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 13735 T135 oasc.ZkController.register We are http://12= 7.0.0.1:33644/collection1/ and leader is http://127.0.0.1:33644/collection1= / [junit4:junit4] 2> 13735 T135 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:33644 [junit4:junit4] 2> 13735 T135 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 13735 T135 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Dactive [junit4:junit4] 2> 13735 T135 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 13812 T135 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13813 T77 oass.SolrDispatchFilter.init user.dir=3D/mnt= /ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 13814 T77 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 13814 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 13877 T77 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 13879 T77 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:59792 [junit4:junit4] 2> 13879 T77 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 13880 T77 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 13880 T77 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-tru= nk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistrib= utedZkTest-jetty3-1361324612135 [junit4:junit4] 2> 13880 T77 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr= /build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-je= tty3-1361324612135/solr.xml [junit4:junit4] 2> 13881 T77 oasc.CoreContainer. New CoreContainer = 11760936 [junit4:junit4] 2> 13881 T77 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr= /build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-je= tty3-1361324612135/' [junit4:junit4] 2> 13881 T77 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty3-1361324612135/' [junit4:junit4] 2> 13898 T77 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 120000 [junit4:junit4] 2> 13898 T77 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 13899 T77 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4:junit4] 2> 13899 T77 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 13899 T77 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 13900 T77 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 13900 T77 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 13900 T77 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 13900 T77 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 13901 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 13908 T77 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 13920 T77 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:48612/solr [junit4:junit4] 2> 13920 T77 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4:junit4] 2> 13921 T77 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:48612 sessionTimeout=3D60000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@a8f9fc [junit4:junit4] 2> 13922 T77 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 13922 T146 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:48612. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 13922 T146 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:48612, init= iating session [junit4:junit4] 2> 13923 T79 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:50952 [junit4:junit4] 2> 13923 T79 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:50952 [junit4:junit4] 2> 13924 T81 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13cf544e47d0009 with negotiated timeout 20000 for client /= 127.0.0.1:50952 [junit4:junit4] 2> 13924 T146 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:48612, = sessionid =3D 0x13cf544e47d0009, negotiated timeout =3D 20000 [junit4:junit4] 2> 13924 T147 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@a8f9fc name:ZooKeeperConnection= Watcher:127.0.0.1:48612 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4:junit4] 2> 13924 T77 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 13925 T82 oazs.PrepRequestProcessor.pRequest2Txn Proce= ssed session termination for sessionid: 0x13cf544e47d0009 [junit4:junit4] 2> 13926 T147 oaz.ClientCnxn$EventThread.run EventThread = shut down [junit4:junit4] 2> 13926 T79 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:50952 which had sessionid 0x13cf544e47d0009 [junit4:junit4] 2> 13926 T77 oaz.ZooKeeper.close Session: 0x13cf544e47d00= 09 closed [junit4:junit4] 2> 13927 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&soc= ketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 13930 T77 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:48612/solr sessionTimeout=3D30000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1446e32 [junit4:junit4] 2> 13931 T148 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:48612. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 13931 T77 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 13931 T148 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:48612, init= iating session [junit4:junit4] 2> 13931 T79 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:50953 [junit4:junit4] 2> 13931 T79 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:50953 [junit4:junit4] 2> 13932 T81 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13cf544e47d000a with negotiated timeout 20000 for client /= 127.0.0.1:50953 [junit4:junit4] 2> 13932 T148 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:48612, = sessionid =3D 0x13cf544e47d000a, negotiated timeout =3D 20000 [junit4:junit4] 2> 13933 T149 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@1446e32 name:ZooKeeperConnectio= n Watcher:127.0.0.1:48612/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 13933 T77 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 13934 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d000a type:crea= te cxid:0x1 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 13943 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d000a type:crea= te cxid:0x2 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 13944 T77 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 14946 T77 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:59792_ [junit4:junit4] 2> 14947 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d000a type:dele= te cxid:0x9 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/12= 7.0.0.1:59792_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.0.= 0.1:59792_ [junit4:junit4] 2> 14949 T77 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:59792_ [junit4:junit4] 2> 14951 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 14952 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 14952 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 14952 T149 oascc.ZkStateReader$3.process Updating live= nodes... (4) [junit4:junit4] 2> 14953 T134 oascc.ZkStateReader$3.process Updating live= nodes... (4) [junit4:junit4] 2> 14953 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 14954 T100 oascc.ZkStateReader$3.process Updating live= nodes... (4) [junit4:junit4] 2> 14955 T106 oascc.ZkStateReader$3.process Updating live= nodes... (4) [junit4:junit4] 2> 14955 T119 oascc.ZkStateReader$3.process Updating live= nodes... (4) [junit4:junit4] 2> 14961 T150 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-tr= unk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistri= butedZkTest-jetty3-1361324612135/collection1 [junit4:junit4] 2> 14962 T150 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 14962 T150 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 14963 T150 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 14964 T150 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Lin= ux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZk= Test-jetty3-1361324612135/collection1/' [junit4:junit4] 2> 14964 T150 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361= 324612135/collection1/lib/README' to classloader [junit4:junit4] 2> 14964 T150 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361= 324612135/collection1/lib/classes/' to classloader [junit4:junit4] 2> 14993 T150 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 15038 T150 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 15039 T150 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 15047 T150 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 15194 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15195 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:33644", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:33644_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 15198 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15198 T149 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15198 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15198 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15198 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15493 T150 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 15501 T150 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 15505 T150 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 15518 T150 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 15521 T150 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 15524 T150 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 15526 T150 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 15527 T150 oasc.SolrCore. [collection1] Opening = new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bui= ld/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3= -1361324612135/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDistri= butedZkTest-1361324598315/jetty3/ [junit4:junit4] 2> 15527 T150 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@417ee9 [junit4:junit4] 2> 15527 T150 oasc.SolrCore.initDirectoryFactory org.apac= he.solr.core.MockDirectoryFactory [junit4:junit4] 2> 15528 T150 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324= 598315/jetty3 forceNew:false [junit4:junit4] 2> 15528 T150 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty3 [junit4:junit4] 2> 15528 T150 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributedZ= kTest-1361324598315/jetty3/index/ [junit4:junit4] 2> 15528 T150 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-13= 61324598315/jetty3/index' doesn't exist. Creating new index... [junit4:junit4] 2> 15529 T150 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324= 598315/jetty3/index forceNew:false [junit4:junit4] 2> 15530 T150 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@1869b75 lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@352ebe)),segFN=3Dsegments_1,generation= =3D1,filenames=3D[segments_1] [junit4:junit4] 2> 15530 T150 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 15531 T150 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty3/index [junit4:junit4] 2> 15532 T150 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 15532 T150 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 15532 T150 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 15533 T150 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 15533 T150 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 15534 T150 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 15534 T150 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 15534 T150 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 15535 T150 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 15537 T150 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 15539 T150 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty3 [junit4:junit4] 2> 15539 T150 oass.SolrIndexSearcher. Opening Searc= her@f15d6a main [junit4:junit4] 2> 15540 T150 oasu.UpdateHandler.clearLog Clearing tlog f= iles, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/jetty3/tlog [junit4:junit4] 2> 15540 T150 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 15540 T150 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 15546 T151 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@f15d6a main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 15548 T150 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Ddown [junit4:junit4] 2> 15548 T150 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 16703 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 16703 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:59792", [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:59792_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 16704 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Collection already exists with numShards=3D2 [junit4:junit4] 2> 16704 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Assigning new node to shard shard=3Dshard2 [junit4:junit4] 2> 16772 T149 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16772 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16772 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16772 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16772 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 17550 T150 oasc.CoreContainer.registerCore registering= core: collection1 [junit4:junit4] 2> 17550 T150 oasc.ZkController.register Register replica= - core:collection1 address:http://127.0.0.1:59792 collection:collection1 s= hard:shard2 [junit4:junit4] 2> 17553 T150 oasc.ZkController.register We are http://12= 7.0.0.1:59792/collection1/ and leader is http://127.0.0.1:57896/collection1= / [junit4:junit4] 2> 17553 T150 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:59792 [junit4:junit4] 2> 17553 T150 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 17553 T150 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C42 name=3Dcollection1 org.apache.solr= .core.SolrCore@61cf6d url=3Dhttp://127.0.0.1:59792/collection1 node=3D127.0= .0.1:59792_ C42_STATE=3Dcoll:collection1 core:collection1 props:{base_url= =3Dhttp://127.0.0.1:59792, state=3Ddown, shard=3Dnull, collection=3Dcollect= ion1, node_name=3D127.0.0.1:59792_, core=3Dcollection1, roles=3Dnull} [junit4:junit4] 2> 17557 T152 C42 P59792 oasc.RecoveryStrategy.run Starti= ng recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 17557 T150 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 17558 T152 C42 P59792 oasc.RecoveryStrategy.doRecovery= ###### startupVersions=3D[] [junit4:junit4] 2> 17558 T152 C42 P59792 oasc.ZkController.publish publis= hing core=3Dcollection1 state=3Drecovering [junit4:junit4] 2> 17558 T152 C42 P59792 oasc.ZkController.publish numSha= rds not found on descriptor - reading it from system property [junit4:junit4] 2> 17558 T77 oass.SolrDispatchFilter.init user.dir=3D/mnt= /ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 17559 T77 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 17559 T152 C42 P59792 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 17559 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 17655 T77 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 17657 T77 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:52994 [junit4:junit4] 2> 17658 T77 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 17659 T77 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 17659 T77 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-tru= nk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistrib= utedZkTest-jetty4-1361324615875 [junit4:junit4] 2> 17660 T77 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr= /build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-je= tty4-1361324615875/solr.xml [junit4:junit4] 2> 17660 T77 oasc.CoreContainer. New CoreContainer = 10526825 [junit4:junit4] 2> 17661 T77 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr= /build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-je= tty4-1361324615875/' [junit4:junit4] 2> 17661 T77 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty4-1361324615875/' [junit4:junit4] 2> 17684 T77 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 120000 [junit4:junit4] 2> 17685 T77 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 17686 T77 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4:junit4] 2> 17686 T77 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 17687 T77 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 17687 T77 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 17688 T77 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 17688 T77 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 17689 T77 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 17690 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 17719 T77 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 17741 T77 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:48612/solr [junit4:junit4] 2> 17741 T77 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4:junit4] 2> 17742 T77 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:48612 sessionTimeout=3D60000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@32abfc [junit4:junit4] 2> 17743 T162 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:48612. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 17743 T77 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 17750 T162 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:48612, init= iating session [junit4:junit4] 2> 17750 T79 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:50955 [junit4:junit4] 2> 17751 T79 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:50955 [junit4:junit4] 2> 17752 T81 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13cf544e47d000b with negotiated timeout 20000 for client /= 127.0.0.1:50955 [junit4:junit4] 2> 17752 T162 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:48612, = sessionid =3D 0x13cf544e47d000b, negotiated timeout =3D 20000 [junit4:junit4] 2> 17752 T163 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@32abfc name:ZooKeeperConnection= Watcher:127.0.0.1:48612 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4:junit4] 2> 17756 T77 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 17758 T82 oazs.PrepRequestProcessor.pRequest2Txn Proce= ssed session termination for sessionid: 0x13cf544e47d000b [junit4:junit4] 2> 17759 T79 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:50955 which had sessionid 0x13cf544e47d000b [junit4:junit4] 2> 17759 T163 oaz.ClientCnxn$EventThread.run EventThread = shut down [junit4:junit4] 2> 17759 T77 oaz.ZooKeeper.close Session: 0x13cf544e47d00= 0b closed [junit4:junit4] 2> 17760 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&soc= ketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 17765 T77 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:48612/solr sessionTimeout=3D30000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@190c55c [junit4:junit4] 2> 17766 T164 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:48612. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 17766 T77 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 17767 T79 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:50956 [junit4:junit4] 2> 17767 T164 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:48612, init= iating session [junit4:junit4] 2> 17768 T79 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:50956 [junit4:junit4] 2> 17769 T81 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13cf544e47d000c with negotiated timeout 20000 for client /= 127.0.0.1:50956 [junit4:junit4] 2> 17769 T164 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:48612, = sessionid =3D 0x13cf544e47d000c, negotiated timeout =3D 20000 [junit4:junit4] 2> 17770 T165 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@190c55c name:ZooKeeperConnectio= n Watcher:127.0.0.1:48612/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 17770 T77 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 17771 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d000c type:crea= te cxid:0x1 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 17772 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d000c type:crea= te cxid:0x2 zxid:0xa3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 17776 T77 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 18278 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 18279 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:59792", [junit4:junit4] 2> =09 "state":"recovering", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:59792_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 18283 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18283 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18283 T149 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18284 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18283 T165 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18283 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18779 T77 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:52994_ [junit4:junit4] 2> 18779 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d000c type:dele= te cxid:0xb zxid:0xa8 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/12= 7.0.0.1:52994_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.0.= 0.1:52994_ [junit4:junit4] 2> 18780 T77 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:52994_ [junit4:junit4] 2> 18782 T149 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18782 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18782 T165 oascc.ZkStateReader$3.process Updating live= nodes... (5) [junit4:junit4] 2> 18782 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 18783 T119 oascc.ZkStateReader$3.process Updating live= nodes... (5) [junit4:junit4] 2> 18782 T165 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18783 T106 oascc.ZkStateReader$3.process Updating live= nodes... (5) [junit4:junit4] 2> 18784 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18783 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18784 T134 oascc.ZkStateReader$3.process Updating live= nodes... (5) [junit4:junit4] 2> 18784 T149 oascc.ZkStateReader$3.process Updating live= nodes... (5) [junit4:junit4] 2> 18786 T100 oascc.ZkStateReader$3.process Updating live= nodes... (5) [junit4:junit4] 2> 18791 T166 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-tr= unk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistri= butedZkTest-jetty4-1361324615875/collection1 [junit4:junit4] 2> 18791 T166 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 18792 T166 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 18792 T166 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 18793 T166 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Lin= ux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZk= Test-jetty4-1361324615875/collection1/' [junit4:junit4] 2> 18793 T166 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361= 324615875/collection1/lib/README' to classloader [junit4:junit4] 2> 18793 T166 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361= 324615875/collection1/lib/classes/' to classloader [junit4:junit4] 2> 18826 T166 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 18890 T166 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 18892 T166 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 18898 T166 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 19353 T166 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 19360 T166 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 19363 T166 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 19376 T166 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 19379 T166 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 19382 T166 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 19383 T166 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 19384 T166 oasc.SolrCore. [collection1] Opening = new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bui= ld/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4= -1361324615875/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDistri= butedZkTest-1361324598315/jetty4/ [junit4:junit4] 2> 19385 T166 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@417ee9 [junit4:junit4] 2> 19385 T166 oasc.SolrCore.initDirectoryFactory org.apac= he.solr.core.MockDirectoryFactory [junit4:junit4] 2> 19385 T166 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324= 598315/jetty4 forceNew:false [junit4:junit4] 2> 19386 T166 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty4 [junit4:junit4] 2> 19386 T166 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributedZ= kTest-1361324598315/jetty4/index/ [junit4:junit4] 2> 19386 T166 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-13= 61324598315/jetty4/index' doesn't exist. Creating new index... [junit4:junit4] 2> 19387 T166 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324= 598315/jetty4/index forceNew:false [junit4:junit4] 2> 19388 T166 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@25a937 lockFactory=3Dorg.apach= e.lucene.store.NativeFSLockFactory@1ae2297)),segFN=3Dsegments_1,generation= =3D1,filenames=3D[segments_1] [junit4:junit4] 2> 19388 T166 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 19388 T166 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty4/index [junit4:junit4] 2> 19390 T166 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 19390 T166 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 19390 T166 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 19391 T166 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 19391 T166 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 19391 T166 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 19392 T166 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 19392 T166 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 19392 T166 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 19395 T166 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 19397 T166 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty4 [junit4:junit4] 2> 19397 T166 oass.SolrIndexSearcher. Opening Searc= her@16611ef main [junit4:junit4] 2> 19398 T166 oasu.UpdateHandler.clearLog Clearing tlog f= iles, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-13613245983= 15/jetty4/tlog [junit4:junit4] 2> 19398 T166 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 19398 T166 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 19402 T167 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@16611ef main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 19404 T166 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Ddown [junit4:junit4] 2> 19404 T166 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 19792 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 19793 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:52994", [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:52994_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 19793 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Collection already exists with numShards=3D2 [junit4:junit4] 2> 19793 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 19796 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19796 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19796 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19796 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19796 T165 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19796 T149 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 20405 T166 oasc.CoreContainer.registerCore registering= core: collection1 [junit4:junit4] 2> 20406 T166 oasc.ZkController.register Register replica= - core:collection1 address:http://127.0.0.1:52994 collection:collection1 s= hard:shard1 [junit4:junit4] 2> 20410 T166 oasc.ZkController.register We are http://12= 7.0.0.1:52994/collection1/ and leader is http://127.0.0.1:33644/collection1= / [junit4:junit4] 2> 20410 T166 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:52994 [junit4:junit4] 2> 20410 T166 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 20410 T166 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C43 name=3Dcollection1 org.apache.solr= .core.SolrCore@1b2e269 url=3Dhttp://127.0.0.1:52994/collection1 node=3D127.= 0.0.1:52994_ C43_STATE=3Dcoll:collection1 core:collection1 props:{base_url= =3Dhttp://127.0.0.1:52994, state=3Ddown, shard=3Dnull, collection=3Dcollect= ion1, node_name=3D127.0.0.1:52994_, core=3Dcollection1, roles=3Dnull} [junit4:junit4] 2> 20411 T168 C43 P52994 oasc.RecoveryStrategy.run Starti= ng recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 20411 T168 C43 P52994 oasc.RecoveryStrategy.doRecovery= ###### startupVersions=3D[] [junit4:junit4] 2> 20412 T166 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 20412 T168 C43 P52994 oasc.ZkController.publish publis= hing core=3Dcollection1 state=3Drecovering [junit4:junit4] 2> 20412 T168 C43 P52994 oasc.ZkController.publish numSha= rds not found on descriptor - reading it from system property [junit4:junit4] 2> 20413 T77 oass.SolrDispatchFilter.init user.dir=3D/mnt= /ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 20413 T168 C43 P52994 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 20413 T77 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 20413 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 20417 T77 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 20419 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 20424 T113 oasc.CoreContainer.create Creating SolrCore= 'unloadcollection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-S= olr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.Unload= DistributedZkTest-jetty1-1361324602897/unloadcollection1 [junit4:junit4] 2> 20425 T113 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:unloadcollection [junit4:junit4] 2> 20425 T113 oasc.ZkController.createCollectionZkNode Cr= eating collection in ZooKeeper:unloadcollection [junit4:junit4] 2> 20426 T113 oasc.ZkController.getConfName Looking for c= ollection configName [junit4:junit4] 2> 20427 T113 oasc.ZkController.getConfName Only one conf= ig set found in zk - using it:conf1 [junit4:junit4] 2> 20427 T113 oascc.SolrZkClient.makePath makePath: /coll= ections/unloadcollection [junit4:junit4] 2> 20429 T113 oasc.ZkController.readConfigName Load colle= ction config from:/collections/unloadcollection [junit4:junit4] 2> 20431 T113 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Lin= ux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZk= Test-jetty1-1361324602897/unloadcollection1/' [junit4:junit4] 2> 20477 T113 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 20520 T113 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 20521 T113 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 20526 T113 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> C42_STATE=3Dcoll:collection1 core:collection1 props:{= base_url=3Dhttp://127.0.0.1:59792, state=3Drecovering, shard=3Dshard2, coll= ection=3Dcollection1, node_name=3D127.0.0.1:59792_, core=3Dcollection1, rol= es=3Dnull} [junit4:junit4] 2> 20884 T152 C42 P59792 oasc.RecoveryStrategy.doRecovery= Attempting to PeerSync from http://127.0.0.1:57896/collection1/ core=3Dcol= lection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 20887 T152 C42 P59792 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnectionsPerHost=3D20&maxConnectio= ns=3D10000&connTimeout=3D30000&socketTimeout=3D30000&retry=3Dfalse [junit4:junit4] 2> 20892 T152 C42 P59792 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:59792 START replicas=3D[http://127.0= .0.1:57896/collection1/] nUpdates=3D100 [junit4:junit4] 2> 20895 T152 C42 P59792 oasu.PeerSync.sync WARNING no fr= ame of reference to tell of we've missed updates [junit4:junit4] 2> 20895 T152 C42 P59792 oasc.RecoveryStrategy.doRecovery= PeerSync Recovery was not successful - trying replication. core=3Dcollecti= on1 [junit4:junit4] 2> 20895 T152 C42 P59792 oasc.RecoveryStrategy.doRecovery= Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 20896 T152 C42 P59792 oasc.RecoveryStrategy.doRecovery= Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 20896 T152 C42 P59792 oasu.UpdateLog.bufferUpdates Sta= rting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 20896 T152 C42 P59792 oasc.RecoveryStrategy.replicate = Attempting to replicate from http://127.0.0.1:57896/collection1/. core=3Dco= llection1 [junit4:junit4] 2> 20896 T152 C42 P59792 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C44 name=3Dcollection1 org.apache.solr= .core.SolrCore@6f6369 url=3Dhttp://127.0.0.1:57896/collection1 node=3D127.0= .0.1:57896_ C44_STATE=3Dcoll:collection1 core:collection1 props:{base_url= =3Dhttp://127.0.0.1:57896, state=3Dactive, shard=3Dshard2, collection=3Dcol= lection1, node_name=3D127.0.0.1:57896_, core=3Dcollection1, roles=3Dnull, l= eader=3Dtrue} [junit4:junit4] 2> 20920 T114 C44 P57896 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 20923 T112 C44 P57896 oasu.DirectUpdateHandler2.commit= start commit{,optimize=3Dfalse,openSearcher=3Dfalse,waitSearcher=3Dtrue,ex= pungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 20924 T112 C44 P57896 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty1 [junit4:junit4] 2> 20925 T112 C44 P57896 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@bcb45a lockFactory=3Dorg.apach= e.lucene.store.NativeFSLockFactory@c8dd5)),segFN=3Dsegments_1,generation=3D= 1,filenames=3D[segments_1] [junit4:junit4] 2> 20925 T112 C44 P57896 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 1[segments_1] [junit4:junit4] 2> 20926 T112 C44 P57896 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@bcb45a lockFactory=3Dorg.apach= e.lucene.store.NativeFSLockFactory@c8dd5)),segFN=3Dsegments_1,generation=3D= 1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@bcb45a lockFactory=3Dorg.apach= e.lucene.store.NativeFSLockFactory@c8dd5)),segFN=3Dsegments_2,generation=3D= 2,filenames=3D[segments_2] [junit4:junit4] 2> 20926 T112 C44 P57896 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2[segments_2] [junit4:junit4] 2> 20927 T112 C44 P57896 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty1 [junit4:junit4] 2> 20927 T112 C44 P57896 oass.SolrIndexSearcher. Op= ening Searcher@62a98a realtime [junit4:junit4] 2> 20927 T112 C44 P57896 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 20927 T112 C44 P57896 /update {softCommit=3Dfalse&open= Searcher=3Dfalse&wt=3Djavabin&waitSearcher=3Dtrue&commit=3Dtrue&version=3D2= &commit_end_point=3Dtrue} {commit=3D} 0 9 [junit4:junit4] 2> 20929 T152 C42 P59792 oash.ReplicationHandler.inform C= ommits will be reserved for 10000 [junit4:junit4] 2> 20929 T152 C42 P59792 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:connTimeout=3D5000&socketTimeout=3D2000= 0&allowCompression=3Dfalse&maxConnections=3D10000&maxConnectionsPerHost=3D1= 0000 [junit4:junit4] 2> 20935 T152 C42 P59792 oash.SnapPuller. No value= set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 20948 T112 C44 P57896 oash.ReplicationHandler.inform C= ommits will be reserved for 10000 [junit4:junit4] 2> 20949 T112 C44 P57896 REQ /replication {command=3Dinde= xversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTime=3D13= =20 [junit4:junit4] 2> 20950 T152 C42 P59792 oash.SnapPuller.fetchLatestIndex= Master's generation: 2 [junit4:junit4] 2> 20950 T152 C42 P59792 oash.SnapPuller.fetchLatestIndex= Slave's generation: 1 [junit4:junit4] 2> 20950 T152 C42 P59792 oash.SnapPuller.fetchLatestIndex= Starting replication process [junit4:junit4] 2> 20953 T115 C44 P57896 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty1 [junit4:junit4] 2> 20953 T115 C44 P57896 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty1/index [junit4:junit4] 2> 20954 T115 C44 P57896 REQ /replication {generation=3D2= &command=3Dfilelist&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 = QTime=3D1=20 [junit4:junit4] 2> 20954 T152 C42 P59792 oash.SnapPuller.fetchLatestIndex= Number of files in latest index in master: 1 [junit4:junit4] 2> 20956 T152 C42 P59792 oasc.CachingDirectoryFactory.get= return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-1361324598315/jetty3/index.20130219224339266 forceNew:false [junit4:junit4] 2> 20956 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3 [junit4:junit4] 2> 20957 T152 C42 P59792 oash.SnapPuller.fetchLatestIndex= Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@a= 7b6c6 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@11b8aad) fu= llCopy=3Dtrue [junit4:junit4] 2> 20964 T114 C44 P57896 REQ /replication {generation=3D2= &command=3Dfilecontent&checksum=3Dtrue&file=3Dsegments_2&qt=3D/replication&= wt=3Dfilestream} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 20981 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3/index [junit4:junit4] 2> 20981 T152 C42 P59792 oash.SnapPuller.fetchLatestIndex= Total time taken for download : 0 secs [junit4:junit4] 2> 20982 T152 C42 P59792 oash.SnapPuller.modifyIndexProps= New index installed. Updating index properties... index=3Dindex.2013021922= 4339266 [junit4:junit4] 2> 20988 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3 [junit4:junit4] 2> 20989 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3 [junit4:junit4] 2> 20989 T152 C42 P59792 oasc.SolrCore.getNewIndexDir New= index directory detected: old=3D./org.apache.solr.cloud.UnloadDistributedZ= kTest-1361324598315/jetty3/index/ new=3D./org.apache.solr.cloud.UnloadDistr= ibutedZkTest-1361324598315/jetty3/index.20130219224339266 [junit4:junit4] 2> 20989 T113 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 20990 T152 C42 P59792 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@a7b6c6 lockFactory=3Dorg.apache.lucene.store.NativeFSLockF= actory@11b8aad),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 20991 T152 C42 P59792 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2[segments_2] [junit4:junit4] 2> 20992 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3 [junit4:junit4] 2> 20993 T152 C42 P59792 oasu.DefaultSolrCoreState.newInd= exWriter Creating new IndexWriter... [junit4:junit4] 2> 20993 T152 C42 P59792 oasu.DefaultSolrCoreState.newInd= exWriter Waiting until IndexWriter is unused... core=3Dcollection1 [junit4:junit4] 2> 20994 T152 C42 P59792 oasu.DefaultSolrCoreState.newInd= exWriter Rollback old IndexWriter... core=3Dcollection1 [junit4:junit4] 2> 20994 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3/index.20130219224339266 [junit4:junit4] 2> 20994 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3 [junit4:junit4] 2> 20995 T152 C42 P59792 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@a7b6c6 lockFactory=3Dorg.apache.lucene.store.NativeFSLockF= actory@11b8aad),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 20995 T152 C42 P59792 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2[segments_2] [junit4:junit4] 2> 20995 T152 C42 P59792 oasu.DefaultSolrCoreState.newInd= exWriter New IndexWriter is ready to be used. [junit4:junit4] 2> 20996 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3 [junit4:junit4] 2> 20995 T113 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 20996 T152 C42 P59792 oass.SolrIndexSearcher. Op= ening Searcher@b75500 main [junit4:junit4] 2> 20997 T151 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@b75500 main{StandardDirectoryReader(segm= ents_2:1:nrt)} [junit4:junit4] 2> 20998 T151 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361324598= 315/jetty3/index [junit4:junit4] 2> 20998 T152 C42 P59792 oasu.DirectUpdateHandler2.commit= start commit{,optimize=3Dfalse,openSearcher=3Dfalse,waitSearcher=3Dfalse,e= xpungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 20998 T152 C42 P59792 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@a7b6c6 lockFactory=3Dorg.apache.lucene.store.NativeFSLockF= actory@11b8aad),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@a7b6c6 lockFactory=3Dorg.apache.lucene.store.NativeFSLockF= actory@11b8aad),segFN=3Dsegments_3,generation=3D3,filenames=3D[segments_3] [junit4:junit4] 2> 20999 T152 C42 P59792 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 3[segments_3] [junit4:junit4] 2> 20999 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3 [junit4:junit4] 2> 20999 T152 C42 P59792 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 20999 T113 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 20999 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3/index.20130219224339266 [junit4:junit4] 2> 21000 T152 C42 P59792 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty3/index [junit4:junit4] 2> 21000 T152 C42 P59792 oasc.RecoveryStrategy.replay No = replay needed. core=3Dcollection1 [junit4:junit4] 2> 21000 T152 C42 P59792 oasc.RecoveryStrategy.doRecovery= Replication Recovery was successful - registering as Active. core=3Dcollec= tion1 [junit4:junit4] 2> 21000 T152 C42 P59792 oasc.ZkController.publish publis= hing core=3Dcollection1 state=3Dactive [junit4:junit4] 2> 21000 T152 C42 P59792 oasc.ZkController.publish numSha= rds not found on descriptor - reading it from system property [junit4:junit4] 2> 21001 T152 C42 P59792 oasc.RecoveryStrategy.doRecovery= Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 21009 T113 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 21014 T113 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 21017 T113 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 21018 T113 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 21019 T113 oasc.SolrCore. [unloadcollection1] Op= ening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-= jetty1-1361324602897/unloadcollection1/, dataDir=3D/mnt/ssd/jenkins/workspa= ce/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDi= stributedZkTest-1361324598315/1361324618733unloadcollection1_1n/ [junit4:junit4] 2> 21019 T113 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@417ee9 [junit4:junit4] 2> 21020 T113 oasc.SolrCore.initDirectoryFactory org.apac= he.solr.core.MockDirectoryFactory [junit4:junit4] 2> 21021 T113 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361324598315/136132= 4618733unloadcollection1_1n forceNew:false [junit4:junit4] 2> 21021 T113 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361324598315/136132461= 8733unloadcollection1_1n [junit4:junit4] 2> 21021 T113 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-tru= nk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-13= 61324598315/1361324618733unloadcollection1_1n/index/ [junit4:junit4] 2> 21022 T113 oasc.SolrCore.initIndex WARNING [unloadcoll= ection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk= -Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361= 324598315/1361324618733unloadcollection1_1n/index' doesn't exist. Creating = new index... [junit4:junit4] 2> 21022 T113 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361324598315/136132= 4618733unloadcollection1_1n/index forceNew:false [junit4:junit4] 2> 21023 T113 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@17f321a lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@1060f95)),segFN=3Dsegments_1,generation= =3D1,filenames=3D[segments_1] [junit4:junit4] 2> 21024 T113 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 21024 T113 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361324598315/136132461= 8733unloadcollection1_1n/index [junit4:junit4] 2> 21025 T113 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 21026 T113 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 21026 T113 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 21026 T113 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 21027 T113 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 21027 T113 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 21027 T113 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 21028 T113 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 21028 T113 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 21031 T113 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 21033 T113 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361324598315/136132461= 8733unloadcollection1_1n [junit4:junit4] 2> 21033 T113 oass.SolrIndexSearcher. Opening Searc= her@1c257da main [junit4:junit4] 2> 21034 T113 oasu.UpdateHandler.clearLog Clearing tlog f= iles, tlogDir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bui= ld/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361324598315/13613= 24618733unloadcollection1_1n/tlog [junit4:junit4] 2> 21034 T113 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 21034 T113 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 21039 T171 oasc.SolrCore.registerSearcher [unloadcolle= ction1] Registered new searcher Searcher@1c257da main{StandardDirectoryRead= er(segments_1:1)} [junit4:junit4] 2> 21042 T113 oasc.ZkController.publish publishing core= =3Dunloadcollection1 state=3Ddown [junit4:junit4] 2> 21301 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 21302 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:52994", [junit4:junit4] 2> =09 "state":"recovering", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:52994_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 21305 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:59792", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:59792_", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 21308 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:57896", [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "collection":"unloadcollection", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:57896_", [junit4:junit4] 2> =09 "core":"unloadcollection1", [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "operation":"state"} [junit4:junit4] 2> 21308 T101 oasc.Overseer$ClusterStateUpdater.createCol= lection Create collection unloadcollection with numShards 1 [junit4:junit4] 2> 21308 T101 oasc.Overseer$ClusterStateUpdater.updateSta= te Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 21312 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21312 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21312 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21312 T165 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21312 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21312 T149 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 22044 T113 oasc.CoreContainer.registerCore registering= core: unloadcollection1 [junit4:junit4] 2> 22044 T113 oasc.ZkController.register Register replica= - core:unloadcollection1 address:http://127.0.0.1:57896 collection:unloadc= ollection shard:shard1 [junit4:junit4] 2> 22045 T113 oascc.SolrZkClient.makePath makePath: /coll= ections/unloadcollection/leader_elect/shard1/election [junit4:junit4] 2> 22051 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d0006 type:dele= te cxid:0xa6 zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/collections/= unloadcollection/leaders Error:KeeperErrorCode =3D NoNode for /solr/collect= ions/unloadcollection/leaders [junit4:junit4] 2> 22051 T113 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 22052 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d0006 type:crea= te cxid:0xa7 zxid:0xc4 txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 22053 T113 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 22053 T113 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 22054 T113 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:57896/unloadcollection1/ [junit4:junit4] 2> 22054 T113 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 22054 T113 oasc.SyncStrategy.syncToMe http://127.0.0.1= :57896/unloadcollection1/ has no replicas [junit4:junit4] 2> 22054 T113 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:57896/unloadcollection1/ [junit4:junit4] 2> 22055 T113 oascc.SolrZkClient.makePath makePath: /coll= ections/unloadcollection/leaders/shard1 [junit4:junit4] 2> 22057 T82 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13cf544e47d0006 type:crea= te cxid:0xb1 zxid:0xc8 txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 22819 T101 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 22826 T134 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 22826 T119 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 22826 T149 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 22826 T106 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 22826 T100 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 22826 T165 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 22860 T113 oasc.ZkController.register We are http://12= 7.0.0.1:57896/unloadcollection1/ and leader is http://127.0.0.1:57896/unloa= dcollection1/ [junit4:junit4] 2> 22861 T113 oasc.ZkController.register No LogReplay nee= ded for core=3Dunloadcollection1 baseURL=3Dhttp://127.0.0.1:57896 [junit4:junit4] 2> 22861 T113 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 22861 T113 oasc.ZkController.publish publishing core= =3Dunloadcollection1 state=3Dactive [junit4:junit4] 2> 22863 T113 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 22864 T113 oasc.CoreContainer.persistFile Persisting c= ores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-= 1361324602897/solr.xml [junit4:junit4] 2> 22873 T113 oasc.SolrXMLSerializer.persistFile Persisti= ng cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty1-1361324602897/solr.xml [junit4:junit4] 2> 22875 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config: [junit4:junit4] 2> 22877 T77 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:48612/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1ee04f5 [junit4:junit4] 2> 22878 T77 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 22878 T172 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:48612. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 22878 T172 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:48612, init= iating session [junit4:junit4] 2> 22878 T79 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:50963 [junit4:junit4] 2> 22879 T79 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:50963 [junit4:junit4] 2> 22879 T81 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13cf544e47d000d with negotiated timeout 10000 for client /= 127.0.0.1:50963 [junit4:junit4] 2> 22879 T172 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:48612, = sessionid =3D 0x13cf544e47d000d, negotiated timeout =3D 10000 [junit4:junit4] 2> 22880 T173 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@1ee04f5 name:ZooKeeperConnectio= n Watcher:127.0.0.1:48612/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 22880 T77 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 22881 T77 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 22883 T77 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 22884 T77 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 22896 T130 oasc.CoreContainer.create Creating SolrCore= 'unloadcollection2' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-S= olr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.Unload= DistributedZkTest-jetty2-1361324607422/unloadcollection2 [junit4:junit4] 2> 22897 T130 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:unloadcollection [junit4:junit4] 2> 22897 T130 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 22898 T130 oasc.ZkController.readConfigName Load colle= ction config from:/collections/unloadcollection [junit4:junit4] 2> 22899 T130 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Lin= ux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZk= Test-jetty2-1361324607422/unloadcollection2/' [junit4:junit4] 2> 22929 T130 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 22975 T130 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 22976 T130 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 22981 T130 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 23415 T130 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> C43_STATE=3Dcoll:collection1 core:collection1 props:{= base_url=3Dhttp://127.0.0.1:52994, state=3Drecovering, shard=3Dshard1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:52994_, core=3Dcollection1, rol= es=3Dnull} [junit4:junit4] 2> 23419 T168 C43 P52994 oasc.RecoveryStrategy.doRecovery= Attempting to PeerSync from http://127.0.0.1:33644/collection1/ core=3Dcol= lection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 23420 T168 C43 P52994 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:52994 START replicas=3D[http://127.0= .0.1:33644/collection1/] nUpdates=3D100 [junit4:junit4] 2> 23420 T168 C43 P52994 oasu.PeerSync.sync WARNING no fr= ame of reference to tell of we've missed updates [junit4:junit4] 2> 23420 T168 C43 P52994 oasc.RecoveryStrategy.doRecovery= PeerSync Recovery was not successful - trying replication. core=3Dcollecti= on1 [junit4:junit4] 2> 23420 T168 C43 P52994 oasc.RecoveryStrategy.doRecovery= Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 23421 T168 C43 P52994 oasc.RecoveryStrategy.doRecovery= Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 23421 T168 C43 P52994 oasu.UpdateLog.bufferUpdates Sta= rting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 23422 T168 C43 P52994 oasc.RecoveryStrategy.replicate = Attempting to replicate from http://127.0.0.1:33644/collection1/. core=3Dco= llection1 [junit4:junit4] 2> 23422 T168 C43 P52994 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 23423 T130 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 23434 T130 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> ASYNC NEW_CORE C45 name=3Dcollection1 org.apache.solr= .core.SolrCore@1dc3b5b url=3Dhttp://127.0.0.1:33644/collection1 node=3D127.= 0.0.1:33644_ C45_STATE=3Dcoll:collection1 core:collection1 props:{base_url= =3Dhttp://127.0.0.1:33644, state=3Dactive, shard=3Dshard1, collection=3Dcol= lection1, node_name=3D127.0.0.1:33644_, core=3Dcollection1, roles=3Dnull, l= eader=3Dtrue} [junit4:junit4] 2> 23435 T128 C45 P33644 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 23440 T129 C45 P33644 oasu.DirectUpdateHandler2.commit= start commit{,optimize=3Dfalse,openSearcher=3Dfalse,waitSearcher=3Dtrue,ex= pungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 23441 T129 C45 P33644 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty2 [junit4:junit4] 2> 23442 T129 C45 P33644 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@16421ed lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@5adb45)),segFN=3Dsegments_1,generation= =3D1,filenames=3D[segments_1] [junit4:junit4] 2> 23443 T129 C45 P33644 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 1[segments_1] [junit4:junit4] 2> 23444 T129 C45 P33644 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@16421ed lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@5adb45)),segFN=3Dsegments_1,generation= =3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(RateLimitedDirectory= Wrapper(org.apache.lucene.store.RAMDirectory@16421ed lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@5adb45)),segFN=3Dsegments_2,generation= =3D2,filenames=3D[segments_2] [junit4:junit4] 2> 23444 T129 C45 P33644 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2[segments_2] [junit4:junit4] 2> 23445 T129 C45 P33644 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty2 [junit4:junit4] 2> 23445 T129 C45 P33644 oass.SolrIndexSearcher. Op= ening Searcher@8a5325 realtime [junit4:junit4] 2> 23446 T129 C45 P33644 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 23446 T129 C45 P33644 /update {softCommit=3Dfalse&open= Searcher=3Dfalse&wt=3Djavabin&waitSearcher=3Dtrue&commit=3Dtrue&version=3D2= &commit_end_point=3Dtrue} {commit=3D} 0 6 [junit4:junit4] 2> 23447 T130 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 23447 T168 C43 P52994 oash.ReplicationHandler.inform C= ommits will be reserved for 10000 [junit4:junit4] 2> 23448 T168 C43 P52994 oash.SnapPuller. No value= set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 23451 T130 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 23452 T129 C45 P33644 oash.ReplicationHandler.inform C= ommits will be reserved for 10000 [junit4:junit4] 2> 23453 T129 C45 P33644 REQ /replication {command=3Dinde= xversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 23454 T168 C43 P52994 oash.SnapPuller.fetchLatestIndex= Master's generation: 2 [junit4:junit4] 2> 23454 T168 C43 P52994 oash.SnapPuller.fetchLatestIndex= Slave's generation: 1 [junit4:junit4] 2> 23454 T168 C43 P52994 oash.SnapPuller.fetchLatestIndex= Starting replication process [junit4:junit4] 2> 23454 T130 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 23455 T130 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 23456 T128 C45 P33644 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty2 [junit4:junit4] 2> 23457 T128 C45 P33644 oasc.CachingDirectoryFactory.clo= se Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1361324598315/jetty2/index [junit4:junit4] 2> 23457 T130 oasc.SolrCore. [unloadcollection2] Op= ening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-tru [...truncated too long message...] t4:junit4] 1> DATA: [junit4:junit4] 1> { [junit4:junit4] 1> "base_url":"http://127.0.0.1:59792", [junit4:junit4] 1> "node_name":"127.0.0.1:59792_", [junit4:junit4] 1> "core":"unloadcollection3"} [junit4:junit4] 1> /solr/collections/unloadcollection/leader_elect (1= ) [junit4:junit4] 1> /solr/collections/unloadcollection/leader_elect/s= hard1 (1) [junit4:junit4] 1> /solr/collections/unloadcollection/leader_elect/= shard1/election (3) [junit4:junit4] 1> /solr/collections/unloadcollection/leader_elect= /shard1/election/89215768880545804-127.0.0.1:52994__unloadcollection4-n_000= 0000003 (0) [junit4:junit4] 1> /solr/collections/unloadcollection/leader_elect= /shard1/election/89215768880545802-127.0.0.1:59792__unloadcollection3-n_000= 0000002 (0) [junit4:junit4] 1> /solr/collections/unloadcollection/leader_elect= /shard1/election/89215768880545800-127.0.0.1:33644__unloadcollection2-n_000= 0000004 (0) [junit4:junit4] 1> /solr/collections/control_collection (3) [junit4:junit4] 1> DATA: [junit4:junit4] 1> {"configName":"conf1"} [junit4:junit4] 1> /solr/collections/control_collection/leaders (1) [junit4:junit4] 1> /solr/collections/control_collection/leaders/shar= d1 (0) [junit4:junit4] 1> DATA: [junit4:junit4] 1> { [junit4:junit4] 1> "base_url":"http://127.0.0.1:52900", [junit4:junit4] 1> "node_name":"127.0.0.1:52900_", [junit4:junit4] 1> "core":"collection1"} [junit4:junit4] 1> /solr/collections/control_collection/leader_elect = (1) [junit4:junit4] 1> /solr/collections/control_collection/leader_elect= /shard1 (1) [junit4:junit4] 1> /solr/collections/control_collection/leader_elec= t/shard1/election (1) [junit4:junit4] 1> /solr/collections/control_collection/leader_ele= ct/shard1/election/89215768880545795-127.0.0.1:52900__collection1-n_0000000= 000 (0) [junit4:junit4] 1> /solr/collections/control_collection/shards (0) [junit4:junit4] 1> /zookeeper (1) [junit4:junit4] 1> DATA: [junit4:junit4] 1> =20 [junit4:junit4] 1>=20 [junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DUnloadDis= tributedZkTest -Dtests.method=3DtestDistribSearch -Dtests.seed=3D49F81467FE= 5CAACA -Dtests.multiplier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dsk -Dtes= ts.timezone=3DAmerica/Argentina/Mendoza -Dtests.file.encoding=3DUTF-8 [junit4:junit4] FAILURE 139s J0 | UnloadDistributedZkTest.testDistribSearc= h <<< [junit4:junit4] > Throwable #1: java.lang.AssertionError: Still found sh= ard [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([49F81467FE5CA= ACA:C81E9A7F8903CAF6]:0) [junit4:junit4] > =09at org.junit.Assert.fail(Assert.java:93) [junit4:junit4] > =09at org.apache.solr.cloud.UnloadDistributedZkTest.te= stUnloadShardAndCollection(UnloadDistributedZkTest.java:120) [junit4:junit4] > =09at org.apache.solr.cloud.UnloadDistributedZkTest.do= Test(UnloadDistributedZkTest.java:73) [junit4:junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase.te= stDistribSearch(BaseDistributedSearchTestCase.java:794) [junit4:junit4] > =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Nat= ive Method) [junit4:junit4] > =09at sun.reflect.NativeMethodAccessorImpl.invoke(Nati= veMethodAccessorImpl.java:57) [junit4:junit4] > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(= DelegatingMethodAccessorImpl.java:43) [junit4:junit4] > =09at java.lang.reflect.Method.invoke(Method.java:474) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.invoke(RandomizedRunner.java:1559) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.access$600(RandomizedRunner.java:79) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$6.evaluate(RandomizedRunner.java:737) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$7.evaluate(RandomizedRunner.java:773) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$8.evaluate(RandomizedRunner.java:787) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleSetupTeardownChai= ned$1.evaluate(TestRuleSetupTeardownChained.java:50) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleFieldCacheSanity$= 1.evaluate(TestRuleFieldCacheSanity.java:51) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:46) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleThreadAndTestName= $1.evaluate(TestRuleThreadAndTestName.java:49) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol.forkTimeoutingTask(ThreadLeakControl.java:782) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$3.evaluate(ThreadLeakControl.java:442) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.runSingleTest(RandomizedRunner.java:746) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$3.evaluate(RandomizedRunner.java:648) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$4.evaluate(RandomizedRunner.java:682) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$5.evaluate(RandomizedRunner.java:693) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:46) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleStoreClassName$1.= evaluate(TestRuleStoreClassName.java:42) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleAssertionsRequire= d$1.evaluate(TestRuleAssertionsRequired.java:43) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$= 1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2> 138687 T77 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4:junit4] 2> 138689 T80 oazs.SessionTrackerImpl.run SessionTrackerI= mpl exited loop! [junit4:junit4] 2> 138698 T76 ccr.ThreadLeakControl.checkThreadLeaks WARN= ING Will linger awaiting termination of 1 leaked thread(s). [junit4:junit4] 2> NOTE: test params are: codec=3DSimpleText, sim=3DDefau= ltSimilarity, locale=3Dsk, timezone=3DAmerica/Argentina/Mendoza [junit4:junit4] 2> NOTE: Linux 3.2.0-37-generic i386/Oracle Corporation 1= .8.0-ea (32-bit)/cpus=3D8,threads=3D2,free=3D242221472,total=3D289406976 [junit4:junit4] 2> NOTE: All tests run in this JVM: [NumericFieldsTest, T= estQuerySenderNoQuery, TestFiltering, DateMathParserTest, SortByFunctionTes= t, TestIBSimilarityFactory, SpatialFilterTest, TestReload, UnloadDistribute= dZkTest] [junit4:junit4] Completed on J0 in 138.99s, 1 test, 1 failure <<< FAILURES! [...truncated 633 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:381: The follo= wing error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:361: The follo= wing error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The follow= ing 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:44= 7: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:= 1202: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:= 865: There were test failures: 254 suites, 1052 tests, 1 failure, 8 ignored= (3 assumptions) Total time: 36 minutes 9 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Description set: Java: 32bit/jdk1.8.0-ea-b65 -client -XX:+UseG1GC Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_16_1315694434.1361325456830 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_16_1315694434.1361325456830--