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 9F61ED52D for ; Sat, 22 Sep 2012 22:40:35 +0000 (UTC) Received: (qmail 77835 invoked by uid 500); 22 Sep 2012 22:40:34 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 77666 invoked by uid 500); 22 Sep 2012 22:40:33 -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 77659 invoked by uid 99); 22 Sep 2012 22:40:33 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 22 Sep 2012 22:40:33 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=5.0 tests=ALL_TRUSTED,NORMAL_HTTP_TO_IP,WEIRD_PORT X-Spam-Check-By: apache.org Received: from [140.211.11.8] (HELO aegis.apache.org) (140.211.11.8) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 22 Sep 2012 22:40:25 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id EA683C00A0 for ; Sat, 22 Sep 2012 22:40:04 +0000 (UTC) Date: Sat, 22 Sep 2012 22:39:52 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <1376656934.151.1348353604946.JavaMail.hudson@aegis.apache.org> In-Reply-To: <1406561893.112.1348335469249.JavaMail.hudson@aegis.apache.org> References: <1406561893.112.1348335469249.JavaMail.hudson@aegis.apache.org> Subject: [JENKINS] Lucene-Solr-Tests-trunk-Java6 - Build # 15287 - Still Failing MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_150_311724086.1348353592562" X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_150_311724086.1348353592562 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java6/15287/ 2 tests failed. REGRESSION: org.apache.solr.cloud.SyncSliceTest.testDistribSearch Error Message: No live SolrServers available to handle this request Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers avail= able to handle this request =09at __randomizedtesting.SeedInfo.seed([85FAE23449DCED29:41C6C2C3E838D15]:= 0) =09at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolr= Server.java:323) =09at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrSe= rver.java:257) =09at org.apache.solr.client.solrj.request.QueryRequest.process(QueryReques= t.java:90) =09at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsist= ency(AbstractFullDistribZkTestBase.java:914) =09at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:226) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:695) =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:616) =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:45) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =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:45) =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:679) FAILED: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch Error Message: Shard still reported as live in zk Stack Trace: java.lang.AssertionError: Shard still reported as live in zk =09at __randomizedtesting.SeedInfo.seed([85FAE23449DCED29:41C6C2C3E838D15]:= 0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(= AbstractFullDistribZkTestBase.java:1225) =09at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSome= DocsAndRecover(BasicDistributedZk2Test.java:190) =09at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributed= Zk2Test.java:83) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:695) =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:616) =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:45) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =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:45) =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:679) Build Log: [...truncated 7048 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.SyncSliceTest [junit4:junit4] 2> 4 T260 oas.SolrTestCaseJ4.setUp ###Starting testDistri= bSearch [junit4:junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/worksp= ace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-S= yncSliceTest-1348352728005 [junit4:junit4] 2> 10 T260 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 144 T261 oaz.Environment.logEnv Server environment:zoo= keeper.version=3D3.3.6-1366786, built on 07/29/2012 06:22 GMT [junit4:junit4] 2> 144 T261 oaz.Environment.logEnv Server environment:hos= t.name=3Dlucene.zones.apache.org [junit4:junit4] 2> 145 T261 oaz.Environment.logEnv Server environment:jav= a.version=3D1.6.0_32 [junit4:junit4] 2> 145 T261 oaz.Environment.logEnv Server environment:jav= a.vendor=3DSun Microsystems Inc. [junit4:junit4] 2> 145 T261 oaz.Environment.logEnv Server environment:jav= a.home=3D/usr/local/openjdk6/jre [junit4:junit4] 2> 145 T261 oaz.Environment.logEnv Server environment:jav= a.class.path=3D/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/classes/test:/usr/home/hudson/hudson-slave/w= orkspace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-test-framework/class= es/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Jav= a6/solr/build/solr-core/test-files:/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-trunk-Java6/lucene/build/codecs/classes/java:/usr/home/hu= dson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/test= -framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-solrj/classes/java:/usr/home/hudson/huds= on-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/class= es/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Jav= a6/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/u= sr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/= build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/= analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/usr/home/huds= on/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/highli= ghter/lucene-highlighter-5.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/wor= kspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/memory/lucene-memory-5.0-= SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trun= k-Java6/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/usr/home/hudson/hud= son-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/spatial/luce= ne-spatial-5.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-Tests-trunk-Java6/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar= :/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/luce= ne/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/usr/home/hudson/hudson-= slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/queries/lucene-q= ueries-5.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT= .jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/= solr/core/lib/commons-cli-1.2.jar:/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/core/lib/commons-codec-1.7.jar:/usr/home/= hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/core/lib/c= ommons-fileupload-1.2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-Tests-trunk-Java6/solr/core/lib/commons-lang-2.6.jar:/usr/home/hudson/= hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/core/lib/easymock= -2.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Ja= va6/solr/core/lib/guava-r05.jar:/usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/core/lib/javax.servlet-api-3.0.1.jar:/usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/core/l= ib/spatial4j-0.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-trunk-Java6/solr/solrj/lib/commons-io-2.1.jar:/usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/httpclient-4.1.= 3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6= /solr/solrj/lib/httpcore-4.1.4.jar:/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/httpmime-4.1.3.jar:/usr/home/h= udson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/j= cl-over-slf4j-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/usr/home/huds= on/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/slf4= j-api-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-t= runk-Java6/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/usr/home/hudson/hudson-sla= ve/workspace/Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/wstx-asl-3.2.7.ja= r:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/sol= r/solrj/lib/zookeeper-3.3.6.jar:/usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/example/lib/jetty-continuation-8.1.2.v20120= 308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Jav= a6/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/usr/home/hudson/hudso= n-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/example/lib/jetty-http= -8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-trunk-Java6/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/usr/home/hud= son/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/example/lib/j= etty-jmx-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene= -Solr-Tests-trunk-Java6/solr/example/lib/jetty-security-8.1.2.v20120308.jar= :/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr= /example/lib/jetty-server-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave= /workspace/Lucene-Solr-Tests-trunk-Java6/solr/example/lib/jetty-servlet-8.1= .2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= trunk-Java6/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/usr/home/hudso= n/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/example/lib/jet= ty-webapp-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucen= e-Solr-Tests-trunk-Java6/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/exa= mple/lib/servlet-api-3.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene= -Solr-Tests-trunk-Java6/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.1= 0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6= /lucene/build/core/classes/java:/usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/lucene/test-framework/lib/junit-4.10.jar:/usr/ho= me/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/test-= framework/lib/randomizedtesting-runner-2.0.1.jar:/home/hudson/hudson-slave/= tools/Ant/ant-1.8.2/lib/ant-launcher.jar:/home/hudson/.ant/lib/ivy-2.2.0.ja= r:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-regexp.jar:/= home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-junit.jar:/home/hudson= /hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-bsf.jar:/home/hudson/hudso= n-slave/tools/Ant/ant-1.8.2/lib/ant-commons-net.jar:/home/hudson/hudson-sla= ve/tools/Ant/ant-1.8.2/lib/ant-antlr.jar:/home/hudson/hudson-slave/tools/An= t/ant-1.8.2/lib/ant-apache-log4j.jar:/home/hudson/hudson-slave/tools/Ant/an= t-1.8.2/lib/ant-commons-logging.jar:/home/hudson/hudson-slave/tools/Ant/ant= -1.8.2/lib/ant-apache-xalan2.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.= 8.2/lib/ant-apache-oro.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/li= b/ant-junit4.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-netr= exx.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-swing.jar:/ho= me/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-javamail.jar:/home/hudso= n/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jsch.jar:/home/hudson/hudson-sla= ve/tools/Ant/ant-1.8.2/lib/ant-jmf.jar:/home/hudson/hudson-slave/tools/Ant/= ant-1.8.2/lib/ant.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant= -apache-bcel.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jai.= jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-testutil.jar:/hom= e/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-resolver.jar:/home= /hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jdepend.jar:/usr/local/ope= njdk6/lib/tools.jar:/home/hudson/.ivy2/cache/com.carrotsearch.randomizedtes= ting/junit4-ant/jars/junit4-ant-2.0.1.jar [junit4:junit4] 2> 147 T261 oaz.Environment.logEnv Server environment:jav= a.library.path=3D/usr/local/openjdk6/jre/lib/amd64/server:/usr/local/openjd= k6/jre/lib/amd64:/usr/local/openjdk6/jre/../lib/amd64:/usr/java/packages/li= b/amd64:/lib:/usr/lib:/usr/local/lib [junit4:junit4] 2> 147 T261 oaz.Environment.logEnv Server environment:jav= a.io.tmpdir=3D. [junit4:junit4] 2> 148 T261 oaz.Environment.logEnv Server environment:jav= a.compiler=3D [junit4:junit4] 2> 148 T261 oaz.Environment.logEnv Server environment:os.= name=3DFreeBSD [junit4:junit4] 2> 148 T261 oaz.Environment.logEnv Server environment:os.= arch=3Damd64 [junit4:junit4] 2> 148 T261 oaz.Environment.logEnv Server environment:os.= version=3D9.0-RELEASE [junit4:junit4] 2> 149 T261 oaz.Environment.logEnv Server environment:use= r.name=3Dhudson [junit4:junit4] 2> 149 T261 oaz.Environment.logEnv Server environment:use= r.home=3D/home/hudson [junit4:junit4] 2> 149 T261 oaz.Environment.logEnv Server environment:use= r.dir=3D/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Jav= a6/solr/build/solr-core/test/J1 [junit4:junit4] 2> 216 T261 oazs.ZooKeeperServer.setTickTime tickTime set= to 1000 [junit4:junit4] 2> 256 T261 oazs.NIOServerCnxn$Factory. binding to = port 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 317 T260 oasc.ZkTestServer.run start zk server on port= :29756 [junit4:junit4] 2> 401 T261 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 508 T260 oaz.Environment.logEnv Client environment:zoo= keeper.version=3D3.3.6-1366786, built on 07/29/2012 06:22 GMT [junit4:junit4] 2> 509 T260 oaz.Environment.logEnv Client environment:hos= t.name=3Dlucene.zones.apache.org [junit4:junit4] 2> 509 T260 oaz.Environment.logEnv Client environment:jav= a.version=3D1.6.0_32 [junit4:junit4] 2> 510 T260 oaz.Environment.logEnv Client environment:jav= a.vendor=3DSun Microsystems Inc. [junit4:junit4] 2> 510 T260 oaz.Environment.logEnv Client environment:jav= a.home=3D/usr/local/openjdk6/jre [junit4:junit4] 2> 511 T260 oaz.Environment.logEnv Client environment:jav= a.class.path=3D/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/classes/test:/usr/home/hudson/hudson-slave/w= orkspace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-test-framework/class= es/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Jav= a6/solr/build/solr-core/test-files:/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-trunk-Java6/lucene/build/codecs/classes/java:/usr/home/hu= dson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/test= -framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-solrj/classes/java:/usr/home/hudson/huds= on-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/class= es/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Jav= a6/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/u= sr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/= build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/= analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/usr/home/huds= on/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/highli= ghter/lucene-highlighter-5.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/wor= kspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/memory/lucene-memory-5.0-= SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trun= k-Java6/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/usr/home/hudson/hud= son-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/spatial/luce= ne-spatial-5.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-Tests-trunk-Java6/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar= :/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/luce= ne/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/usr/home/hudson/hudson-= slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/build/queries/lucene-q= ueries-5.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT= .jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/= solr/core/lib/commons-cli-1.2.jar:/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/core/lib/commons-codec-1.7.jar:/usr/home/= hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/core/lib/c= ommons-fileupload-1.2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-Tests-trunk-Java6/solr/core/lib/commons-lang-2.6.jar:/usr/home/hudson/= hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/core/lib/easymock= -2.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Ja= va6/solr/core/lib/guava-r05.jar:/usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/core/lib/javax.servlet-api-3.0.1.jar:/usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/core/l= ib/spatial4j-0.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-trunk-Java6/solr/solrj/lib/commons-io-2.1.jar:/usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/httpclient-4.1.= 3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6= /solr/solrj/lib/httpcore-4.1.4.jar:/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/httpmime-4.1.3.jar:/usr/home/h= udson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/j= cl-over-slf4j-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/usr/home/huds= on/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/slf4= j-api-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-t= runk-Java6/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/usr/home/hudson/hudson-sla= ve/workspace/Lucene-Solr-Tests-trunk-Java6/solr/solrj/lib/wstx-asl-3.2.7.ja= r:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/sol= r/solrj/lib/zookeeper-3.3.6.jar:/usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/example/lib/jetty-continuation-8.1.2.v20120= 308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Jav= a6/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/usr/home/hudson/hudso= n-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/example/lib/jetty-http= -8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-trunk-Java6/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/usr/home/hud= son/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/example/lib/j= etty-jmx-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene= -Solr-Tests-trunk-Java6/solr/example/lib/jetty-security-8.1.2.v20120308.jar= :/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr= /example/lib/jetty-server-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave= /workspace/Lucene-Solr-Tests-trunk-Java6/solr/example/lib/jetty-servlet-8.1= .2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= trunk-Java6/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/usr/home/hudso= n/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/example/lib/jet= ty-webapp-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucen= e-Solr-Tests-trunk-Java6/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/exa= mple/lib/servlet-api-3.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene= -Solr-Tests-trunk-Java6/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.1= 0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6= /lucene/build/core/classes/java:/usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/lucene/test-framework/lib/junit-4.10.jar:/usr/ho= me/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucene/test-= framework/lib/randomizedtesting-runner-2.0.1.jar:/home/hudson/hudson-slave/= tools/Ant/ant-1.8.2/lib/ant-launcher.jar:/home/hudson/.ant/lib/ivy-2.2.0.ja= r:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-regexp.jar:/= home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-junit.jar:/home/hudson= /hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-bsf.jar:/home/hudson/hudso= n-slave/tools/Ant/ant-1.8.2/lib/ant-commons-net.jar:/home/hudson/hudson-sla= ve/tools/Ant/ant-1.8.2/lib/ant-antlr.jar:/home/hudson/hudson-slave/tools/An= t/ant-1.8.2/lib/ant-apache-log4j.jar:/home/hudson/hudson-slave/tools/Ant/an= t-1.8.2/lib/ant-commons-logging.jar:/home/hudson/hudson-slave/tools/Ant/ant= -1.8.2/lib/ant-apache-xalan2.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.= 8.2/lib/ant-apache-oro.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/li= b/ant-junit4.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-netr= exx.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-swing.jar:/ho= me/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-javamail.jar:/home/hudso= n/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jsch.jar:/home/hudson/hudson-sla= ve/tools/Ant/ant-1.8.2/lib/ant-jmf.jar:/home/hudson/hudson-slave/tools/Ant/= ant-1.8.2/lib/ant.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant= -apache-bcel.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jai.= jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-testutil.jar:/hom= e/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-resolver.jar:/home= /hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jdepend.jar:/usr/local/ope= njdk6/lib/tools.jar:/home/hudson/.ivy2/cache/com.carrotsearch.randomizedtes= ting/junit4-ant/jars/junit4-ant-2.0.1.jar [junit4:junit4] 2> 512 T260 oaz.Environment.logEnv Client environment:jav= a.library.path=3D/usr/local/openjdk6/jre/lib/amd64/server:/usr/local/openjd= k6/jre/lib/amd64:/usr/local/openjdk6/jre/../lib/amd64:/usr/java/packages/li= b/amd64:/lib:/usr/lib:/usr/local/lib [junit4:junit4] 2> 513 T260 oaz.Environment.logEnv Client environment:jav= a.io.tmpdir=3D. [junit4:junit4] 2> 513 T260 oaz.Environment.logEnv Client environment:jav= a.compiler=3D [junit4:junit4] 2> 514 T260 oaz.Environment.logEnv Client environment:os.= name=3DFreeBSD [junit4:junit4] 2> 514 T260 oaz.Environment.logEnv Client environment:os.= arch=3Damd64 [junit4:junit4] 2> 515 T260 oaz.Environment.logEnv Client environment:os.= version=3D9.0-RELEASE [junit4:junit4] 2> 515 T260 oaz.Environment.logEnv Client environment:use= r.name=3Dhudson [junit4:junit4] 2> 516 T260 oaz.Environment.logEnv Client environment:use= r.home=3D/home/hudson [junit4:junit4] 2> 516 T260 oaz.Environment.logEnv Client environment:use= r.dir=3D/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Jav= a6/solr/build/solr-core/test/J1 [junit4:junit4] 2> 524 T260 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:29756 sessionTimeout=3D10000 watcher=3Dorg.= apache.solr.common.cloud.ConnectionManager@2d03e677 [junit4:junit4] 2> 643 T265 oaz.ClientCnxn$SendThread.startConnect Openin= g socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 644 T260 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 661 T265 oaz.ClientCnxn$SendThread.primeConnection Soc= ket connection established to localhost/127.0.0.1:29756, initiating session [junit4:junit4] 2> 661 T262 oazs.NIOServerCnxn$Factory.run Accepted socke= t connection from /140.211.11.196:29757 [junit4:junit4] 2> 684 T262 oazs.NIOServerCnxn.readConnectRequest Client = attempting to establish new session at /140.211.11.196:29757 [junit4:junit4] 2> 700 T264 oazsp.FileTxnLog.append Creating new log file= : log.1 [junit4:junit4] 2> 758 T264 oazs.NIOServerCnxn.finishSessionInit Establis= hed session 0x139f015dda50000 with negotiated timeout 10000 for client /140= .211.11.196:29757 [junit4:junit4] 2> 758 T265 oaz.ClientCnxn$SendThread.readConnectResult S= ession establishment complete on server localhost/127.0.0.1:29756, sessioni= d =3D 0x139f015dda50000, negotiated timeout =3D 10000 [junit4:junit4] 2> 771 T267 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@2d03e677 name:ZooKeeperConnection= Watcher:127.0.0.1:29756 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4:junit4] 2> 771 T260 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 775 T260 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 866 T266 oazs.PrepRequestProcessor.pRequest Processed = session termination for sessionid: 0x139f015dda50000 [junit4:junit4] 2> 906 T267 oaz.ClientCnxn$EventThread.run EventThread sh= ut down [junit4:junit4] 2> 906 T260 oaz.ZooKeeper.close Session: 0x139f015dda5000= 0 closed [junit4:junit4] 2> 907 T260 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:29756/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@515f9553 [junit4:junit4] 2> 908 T262 oazs.NIOServerCnxn.closeSock Closed socket co= nnection for client /140.211.11.196:29757 which had sessionid 0x139f015dda5= 0000 [junit4:junit4] 2> 908 T268 oaz.ClientCnxn$SendThread.startConnect Openin= g socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 908 T260 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 909 T268 oaz.ClientCnxn$SendThread.primeConnection Soc= ket connection established to localhost/127.0.0.1:29756, initiating session [junit4:junit4] 2> 910 T262 oazs.NIOServerCnxn$Factory.run Accepted socke= t connection from /140.211.11.196:29758 [junit4:junit4] 2> 910 T262 oazs.NIOServerCnxn.readConnectRequest Client = attempting to establish new session at /140.211.11.196:29758 [junit4:junit4] 2> 942 T264 oazs.NIOServerCnxn.finishSessionInit Establis= hed session 0x139f015dda50001 with negotiated timeout 10000 for client /140= .211.11.196:29758 [junit4:junit4] 2> 942 T268 oaz.ClientCnxn$SendThread.readConnectResult S= ession establishment complete on server localhost/127.0.0.1:29756, sessioni= d =3D 0x139f015dda50001, negotiated timeout =3D 10000 [junit4:junit4] 2> 943 T269 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@515f9553 name:ZooKeeperConnection= Watcher:127.0.0.1:29756/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 943 T260 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 949 T260 oascc.SolrZkClient.makePath makePath: /collec= tions/collection1 [junit4:junit4] 2> 974 T260 oascc.SolrZkClient.makePath makePath: /collec= tions/collection1/shards [junit4:junit4] 2> 988 T260 oascc.SolrZkClient.makePath makePath: /collec= tions/control_collection [junit4:junit4] 2> 992 T260 oascc.SolrZkClient.makePath makePath: /collec= tions/control_collection/shards [junit4:junit4] 2> 1004 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1= /solrconfig.xml [junit4:junit4] 2> 1005 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 1019 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/sch= ema.xml [junit4:junit4] 2> 1020 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 1128 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1= /solrconfig.xml [junit4:junit4] 2> 1129 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 1145 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/= stopwords.txt [junit4:junit4] 2> 1146 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 1155 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/= protwords.txt [junit4:junit4] 2> 1156 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 1169 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/c= urrency.xml [junit4:junit4] 2> 1170 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 1174 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /con= figs/conf1/open-exchange-rates.json [junit4:junit4] 2> 1175 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 1179 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /= configs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 1180 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 1185 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/con= f1/old_synonyms.txt [junit4:junit4] 2> 1185 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 1190 T260 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/= solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/s= ynonyms.txt [junit4:junit4] 2> 1191 T260 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 1195 T266 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139f015dda50001 [junit4:junit4] 2> 1196 T269 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 1196 T260 oaz.ZooKeeper.close Session: 0x139f015dda500= 01 closed [junit4:junit4] 2> 1196 T262 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:29758 which had sessionid 0x139f015dda= 50001 [junit4:junit4] 2> 1354 T260 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 1357 T260 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:29760 [junit4:junit4] 2> 1358 T260 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1359 T260 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-controljetty-1348352729198 [junit4:junit4] 2> 1359 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.SyncSliceTest-controljetty-1348352729198/' [junit4:junit4] 2> 1415 T260 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 1416 T260 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1416 T260 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-controljetty-1348352729198 [junit4:junit4] 2> 1417 T260 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSli= ceTest-controljetty-1348352729198/solr.xml [junit4:junit4] 2> 1418 T260 oasc.CoreContainer. New CoreContainer = 147591426 [junit4:junit4] 2> 1419 T260 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSli= ceTest-controljetty-1348352729198/' [junit4:junit4] 2> 1419 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.S= yncSliceTest-controljetty-1348352729198/' [junit4:junit4] 2> 1461 T260 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 1478 T260 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:29756/solr [junit4:junit4] 2> 1510 T260 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:29756/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@3ecef542 [junit4:junit4] 2> 1512 T279 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 1512 T260 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 1513 T279 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:29756, initiating sessio= n [junit4:junit4] 2> 1513 T262 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:29761 [junit4:junit4] 2> 1513 T262 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:29761 [junit4:junit4] 2> 1517 T264 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139f015dda50002 with negotiated timeout 5000 for client /140= .211.11.196:29761 [junit4:junit4] 2> 1517 T279 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:29756, session= id =3D 0x139f015dda50002, negotiated timeout =3D 5000 [junit4:junit4] 2> 1517 T280 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@3ecef542 name:ZooKeeperConnectio= n Watcher:127.0.0.1:29756/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 1517 T260 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 1546 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1559 T260 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4:junit4] 2> 1562 T260 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:29760_solr [junit4:junit4] 2> 1567 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:dele= te cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:29760_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:29760_solr [junit4:junit4] 2> 1568 T260 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:29760_solr [junit4:junit4] 2> 1572 T260 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 1572 T260 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 1572 T260 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 1572 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1573 T260 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 1573 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1573 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1573 T260 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 1574 T260 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 1574 T260 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 1586 T260 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4:junit4] 2> 1615 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:dele= te cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/ove= rseer_elect/leader [junit4:junit4] 2> 1616 T260 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4:junit4] 2> 1620 T260 oasc.Overseer.start Overseer (id=3D883656444= 13992962-127.0.0.1:29760_solr-n_0000000000) starting [junit4:junit4] 2> 1620 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1641 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1642 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1648 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1649 T282 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 1651 T260 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4:junit4] 2> 1658 T260 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1681 T281 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 1688 T260 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-controljetty-1348352729198/collection1 [junit4:junit4] 2> 1688 T260 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4:junit4] 2> 1689 T260 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 1689 T260 oasc.ZkController.readConfigName Load collec= tion config from:/collections/control_collection [junit4:junit4] 2> 1691 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.S= yncSliceTest-controljetty-1348352729198/collection1/' [junit4:junit4] 2> 1692 T260 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-con= troljetty-1348352729198/collection1/lib/classes/' to classloader [junit4:junit4] 2> 1693 T260 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-con= troljetty-1348352729198/collection1/lib/README' to classloader [junit4:junit4] 2> 1751 T260 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 1812 T260 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 1913 T260 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 1921 T260 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 2562 T260 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 2574 T260 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 2579 T260 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 2601 T260 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 2609 T260 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 2615 T260 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2617 T260 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2619 T260 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTes= t-controljetty-1348352729198/collection1/, dataDir=3D./org.apache.solr.clou= d.SyncSliceTest-1348352728005/control/data/ [junit4:junit4] 2> 2620 T260 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@5a92668c [junit4:junit4] 2> 2655 T260 oasc.SolrCore.getNewIndexDir WARNING New ind= ex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTe= st-1348352728005/control/data/index/ [junit4:junit4] 2> 2656 T260 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1348352728005= /control/data/index' doesn't exist. Creating new index... [junit4:junit4] 2> 2657 T260 oasc.CachingDirectoryFactory.get return new = directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest= -1348352728005/control/data/index forceNew:false [junit4:junit4] 2> 2671 T260 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/control/data/index,segFN=3Dsegments_= 1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 2672 T260 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1 [junit4:junit4] 2> 2673 T260 oasc.SolrCore.initWriters created xml: solr.= XMLResponseWriter [junit4:junit4] 2> 2676 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 2677 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe-allfields" [junit4:junit4] 2> 2678 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 2679 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "un= iq-fields" [junit4:junit4] 2> 2680 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 2681 T260 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 2682 T260 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 2683 T260 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 2684 T260 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 2685 T260 oasc.RequestHandlers.initHandlersFromConfig = created dismax: solr.SearchHandler [junit4:junit4] 2> 2686 T260 oasc.RequestHandlers.initHandlersFromConfig = created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 2687 T260 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 2688 T260 oasc.RequestHandlers.initHandlersFromConfig = created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 2688 T260 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 2689 T260 oasc.RequestHandlers.initHandlersFromConfig = created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 2690 T260 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 2691 T260 oasc.RequestHandlers.initHandlersFromConfig = created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2692 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2693 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 2694 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 2695 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.S= earchHandler [junit4:junit4] 2> 2696 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2697 T260 oasc.RequestHandlers.initHandlersFromConfig = created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2698 T260 oasc.RequestHandlers.initHandlersFromConfig = created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 2699 T260 oasc.RequestHandlers.initHandlersFromConfig = created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 2705 T260 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 2713 T260 oasc.SolrCore.initDeprecatedSupport WARNING = solrconfig.xml uses deprecated , Please update your co= nfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 2715 T260 oasc.SolrCore.initDeprecatedSupport WARNING = adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.= TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, = CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 2723 T260 oass.SolrIndexSearcher. Opening Search= er@51527a1e main [junit4:junit4] 2> 2725 T260 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 2726 T260 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 2727 T260 oashc.SpellCheckComponent.inform Initializin= g spell checkers [junit4:junit4] 2> 2744 T260 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 2802 T283 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@51527a1e main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 2809 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer/queue Error:KeeperErrorCode =3D NoNode for /solr/overseer/q= ueue [junit4:junit4] 2> 2826 T260 oasc.CoreContainer.register registering core= : collection1 [junit4:junit4] 2> 2827 T260 oasc.ZkController.register Register shard - = core:collection1 address:http://127.0.0.1:29760/solr shardId:control_shard [junit4:junit4] 2> 2840 T260 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D10000&maxConnectionsPerHost=3D20&c= onnTimeout=3D30000&socketTimeout=3D30000&retry=3Dfalse [junit4:junit4] 2> 2856 T260 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/leader_elect/control_shard/election [junit4:junit4] 2> 2887 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:dele= te cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/collections/control_collection/leaders Error:KeeperErrorCode =3D NoN= ode for /solr/collections/control_collection/leaders [junit4:junit4] 2> 2888 T260 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process. [junit4:junit4] 2> 2889 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3193 T281 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3209 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer/queue-work Error:KeeperErrorCode =3D NoNode for /solr/overs= eer/queue-work [junit4:junit4] 2> 3285 T280 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 3408 T260 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4:junit4] 2> 3409 T260 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4:junit4] 2> 3410 T260 oasc.SyncStrategy.sync Sync replicas to http= ://127.0.0.1:29760/solr/collection1/ [junit4:junit4] 2> 3410 T260 oasc.SyncStrategy.syncReplicas Sync Success = - now sync replicas to me [junit4:junit4] 2> 3411 T260 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 29760/solr/collection1/ has no replicas [junit4:junit4] 2> 3411 T260 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:29760/solr/collection1/ [junit4:junit4] 2> 3412 T260 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/leaders/control_shard [junit4:junit4] 2> 3432 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50002 type:crea= te cxid:0x8b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3821 T281 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3840 T280 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 3856 T260 oasc.ZkController.register We are http://127= .0.0.1:29760/solr/collection1/ and leader is http://127.0.0.1:29760/solr/co= llection1/ [junit4:junit4] 2> 3856 T260 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:29760/solr [junit4:junit4] 2> 3857 T260 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4:junit4] 2> 3860 T260 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3861 T260 oass.SolrDispatchFilter.init user.dir=3D/usr= /home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 3861 T260 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 3862 T260 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 4032 T260 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 4034 T260 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:29763 [junit4:junit4] 2> 4035 T260 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4035 T260 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty1-1348352731873 [junit4:junit4] 2> 4036 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.SyncSliceTest-jetty1-1348352731873/' [junit4:junit4] 2> 4079 T260 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 4080 T260 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4080 T260 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty1-1348352731873 [junit4:junit4] 2> 4080 T260 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSli= ceTest-jetty1-1348352731873/solr.xml [junit4:junit4] 2> 4081 T260 oasc.CoreContainer. New CoreContainer = 1966818831 [junit4:junit4] 2> 4081 T260 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSli= ceTest-jetty1-1348352731873/' [junit4:junit4] 2> 4082 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.S= yncSliceTest-jetty1-1348352731873/' [junit4:junit4] 2> 4106 T260 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 4120 T260 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:29756/solr [junit4:junit4] 2> 4121 T260 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:29756/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@5aa1f7f5 [junit4:junit4] 2> 4122 T293 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 4122 T260 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 4123 T293 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:29756, initiating sessio= n [junit4:junit4] 2> 4124 T262 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:29765 [junit4:junit4] 2> 4124 T262 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:29765 [junit4:junit4] 2> 4128 T264 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139f015dda50003 with negotiated timeout 5000 for client /140= .211.11.196:29765 [junit4:junit4] 2> 4129 T293 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:29756, session= id =3D 0x139f015dda50003, negotiated timeout =3D 5000 [junit4:junit4] 2> 4129 T294 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@5aa1f7f5 name:ZooKeeperConnectio= n Watcher:127.0.0.1:29756/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 4129 T260 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 4130 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50003 type:crea= te cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4137 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50003 type:crea= te cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4145 T260 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:29763_solr [junit4:junit4] 2> 4146 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50003 type:dele= te cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:29763_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:29763_solr [junit4:junit4] 2> 4153 T260 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:29763_solr [junit4:junit4] 2> 4164 T280 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 4165 T260 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 4166 T260 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 4166 T260 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 4167 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 4167 T260 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 4167 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 4168 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 4168 T260 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 4169 T260 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 4169 T260 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 4194 T260 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 4201 T260 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty1-1348352731873/collection1 [junit4:junit4] 2> 4202 T260 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4:junit4] 2> 4203 T260 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 4203 T260 oasc.ZkController.readConfigName Load collec= tion config from:/collections/collection1 [junit4:junit4] 2> 4205 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.S= yncSliceTest-jetty1-1348352731873/collection1/' [junit4:junit4] 2> 4207 T260 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jet= ty1-1348352731873/collection1/lib/README' to classloader [junit4:junit4] 2> 4208 T260 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jet= ty1-1348352731873/collection1/lib/classes/' to classloader [junit4:junit4] 2> 4261 T260 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 4333 T260 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 4346 T281 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4359 T280 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 4434 T294 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 4434 T260 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 4443 T260 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 5082 T260 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 5095 T260 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 5099 T260 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 5119 T260 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 5125 T260 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 5130 T260 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5132 T260 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5134 T260 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTes= t-jetty1-1348352731873/collection1/, dataDir=3D./org.apache.solr.cloud.Sync= SliceTest-1348352728005/jetty1/ [junit4:junit4] 2> 5134 T260 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@5a92668c [junit4:junit4] 2> 5136 T260 oasc.SolrCore.getNewIndexDir WARNING New ind= ex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTe= st-1348352728005/jetty1/index/ [junit4:junit4] 2> 5137 T260 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1348352728005= /jetty1/index' doesn't exist. Creating new index... [junit4:junit4] 2> 5137 T260 oasc.CachingDirectoryFactory.get return new = directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest= -1348352728005/jetty1/index forceNew:false [junit4:junit4] 2> 5152 T260 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty1/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 5152 T260 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1 [junit4:junit4] 2> 5153 T260 oasc.SolrCore.initWriters created xml: solr.= XMLResponseWriter [junit4:junit4] 2> 5155 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 5156 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe-allfields" [junit4:junit4] 2> 5156 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 5157 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "un= iq-fields" [junit4:junit4] 2> 5158 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 5158 T260 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 5159 T260 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 5160 T260 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 5161 T260 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 5161 T260 oasc.RequestHandlers.initHandlersFromConfig = created dismax: solr.SearchHandler [junit4:junit4] 2> 5162 T260 oasc.RequestHandlers.initHandlersFromConfig = created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 5163 T260 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 5164 T260 oasc.RequestHandlers.initHandlersFromConfig = created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 5164 T260 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 5165 T260 oasc.RequestHandlers.initHandlersFromConfig = created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 5165 T260 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 5166 T260 oasc.RequestHandlers.initHandlersFromConfig = created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5167 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5168 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 5168 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 5169 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.S= earchHandler [junit4:junit4] 2> 5170 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5171 T260 oasc.RequestHandlers.initHandlersFromConfig = created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5171 T260 oasc.RequestHandlers.initHandlersFromConfig = created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 5172 T260 oasc.RequestHandlers.initHandlersFromConfig = created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 5176 T260 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 5180 T260 oasc.SolrCore.initDeprecatedSupport WARNING = solrconfig.xml uses deprecated , Please update your co= nfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 5182 T260 oasc.SolrCore.initDeprecatedSupport WARNING = adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.= TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, = CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 5186 T260 oass.SolrIndexSearcher. Opening Search= er@65a09e0b main [junit4:junit4] 2> 5187 T260 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 5188 T260 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 5189 T260 oashc.SpellCheckComponent.inform Initializin= g spell checkers [junit4:junit4] 2> 5209 T260 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 5269 T295 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@65a09e0b main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 5374 T281 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5397 T280 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 5397 T294 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 5777 T260 oasc.CoreContainer.register registering core= : collection1 [junit4:junit4] 2> 5777 T260 oasc.ZkController.register Register shard - = core:collection1 address:http://127.0.0.1:29763/solr shardId:shard1 [junit4:junit4] 2> 5779 T260 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leader_elect/shard1/election [junit4:junit4] 2> 5963 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50003 type:dele= te cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/collections/collection1/leaders Error:KeeperErrorCode =3D NoNode for= /solr/collections/collection1/leaders [junit4:junit4] 2> 5997 T260 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process. [junit4:junit4] 2> 5997 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50003 type:crea= te cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6001 T260 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4:junit4] 2> 6001 T260 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4:junit4] 2> 6002 T260 oasc.SyncStrategy.sync Sync replicas to http= ://127.0.0.1:29763/solr/collection1/ [junit4:junit4] 2> 6002 T260 oasc.SyncStrategy.syncReplicas Sync Success = - now sync replicas to me [junit4:junit4] 2> 6003 T260 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 29763/solr/collection1/ has no replicas [junit4:junit4] 2> 6003 T260 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:29763/solr/collection1/ [junit4:junit4] 2> 6003 T260 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leaders/shard1 [junit4:junit4] 2> 6015 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50003 type:crea= te cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6465 T281 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6501 T280 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 6501 T294 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 6527 T260 oasc.ZkController.register We are http://127= .0.0.1:29763/solr/collection1/ and leader is http://127.0.0.1:29763/solr/co= llection1/ [junit4:junit4] 2> 6527 T260 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:29763/solr [junit4:junit4] 2> 6528 T260 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4:junit4] 2> 6530 T260 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6531 T260 oass.SolrDispatchFilter.init user.dir=3D/usr= /home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 6532 T260 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 6532 T260 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 6712 T260 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 6715 T260 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:29766 [junit4:junit4] 2> 6715 T260 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 6716 T260 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty2-1348352734548 [junit4:junit4] 2> 6716 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.SyncSliceTest-jetty2-1348352734548/' [junit4:junit4] 2> 6751 T260 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 6752 T260 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 6752 T260 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty2-1348352734548 [junit4:junit4] 2> 6753 T260 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSli= ceTest-jetty2-1348352734548/solr.xml [junit4:junit4] 2> 6753 T260 oasc.CoreContainer. New CoreContainer = 1170890362 [junit4:junit4] 2> 6754 T260 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSli= ceTest-jetty2-1348352734548/' [junit4:junit4] 2> 6754 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.S= yncSliceTest-jetty2-1348352734548/' [junit4:junit4] 2> 6778 T260 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 6794 T260 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:29756/solr [junit4:junit4] 2> 6795 T260 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:29756/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@3014af22 [junit4:junit4] 2> 6796 T305 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 6796 T260 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 6797 T305 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:29756, initiating sessio= n [junit4:junit4] 2> 6797 T262 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:29767 [junit4:junit4] 2> 6798 T262 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:29767 [junit4:junit4] 2> 6809 T264 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139f015dda50004 with negotiated timeout 5000 for client /140= .211.11.196:29767 [junit4:junit4] 2> 6809 T305 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:29756, session= id =3D 0x139f015dda50004, negotiated timeout =3D 5000 [junit4:junit4] 2> 6809 T306 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@3014af22 name:ZooKeeperConnectio= n Watcher:127.0.0.1:29756/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 6809 T260 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 6810 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50004 type:crea= te cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6819 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50004 type:crea= te cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6821 T260 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:29766_solr [junit4:junit4] 2> 6822 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50004 type:dele= te cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:29766_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:29766_solr [junit4:junit4] 2> 6827 T260 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:29766_solr [junit4:junit4] 2> 6829 T294 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 6829 T280 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 6831 T260 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 6831 T260 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 6832 T260 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 6832 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 6832 T260 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 6833 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 6833 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 6834 T260 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 6834 T260 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 6835 T260 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 6855 T260 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 6863 T260 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty2-1348352734548/collection1 [junit4:junit4] 2> 6863 T260 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4:junit4] 2> 6864 T260 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 6864 T260 oasc.ZkController.readConfigName Load collec= tion config from:/collections/collection1 [junit4:junit4] 2> 6866 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.S= yncSliceTest-jetty2-1348352734548/collection1/' [junit4:junit4] 2> 6868 T260 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jet= ty2-1348352734548/collection1/lib/classes/' to classloader [junit4:junit4] 2> 6869 T260 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jet= ty2-1348352734548/collection1/lib/README' to classloader [junit4:junit4] 2> 6923 T260 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 7004 T260 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 7020 T281 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7047 T280 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 7047 T294 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 7105 T306 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 7105 T260 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 7114 T260 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 7732 T260 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 7744 T260 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 7747 T260 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 7763 T260 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 7767 T260 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 7771 T260 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 7772 T260 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 7774 T260 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTes= t-jetty2-1348352734548/collection1/, dataDir=3D./org.apache.solr.cloud.Sync= SliceTest-1348352728005/jetty2/ [junit4:junit4] 2> 7774 T260 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@5a92668c [junit4:junit4] 2> 7775 T260 oasc.SolrCore.getNewIndexDir WARNING New ind= ex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTe= st-1348352728005/jetty2/index/ [junit4:junit4] 2> 7776 T260 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1348352728005= /jetty2/index' doesn't exist. Creating new index... [junit4:junit4] 2> 7776 T260 oasc.CachingDirectoryFactory.get return new = directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest= -1348352728005/jetty2/index forceNew:false [junit4:junit4] 2> 7795 T260 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty2/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 7795 T260 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1 [junit4:junit4] 2> 7796 T260 oasc.SolrCore.initWriters created xml: solr.= XMLResponseWriter [junit4:junit4] 2> 7798 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 7798 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe-allfields" [junit4:junit4] 2> 7798 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 7799 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "un= iq-fields" [junit4:junit4] 2> 7799 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 7800 T260 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 7800 T260 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 7800 T260 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 7801 T260 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 7801 T260 oasc.RequestHandlers.initHandlersFromConfig = created dismax: solr.SearchHandler [junit4:junit4] 2> 7802 T260 oasc.RequestHandlers.initHandlersFromConfig = created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 7802 T260 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 7803 T260 oasc.RequestHandlers.initHandlersFromConfig = created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 7803 T260 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 7803 T260 oasc.RequestHandlers.initHandlersFromConfig = created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 7804 T260 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 7804 T260 oasc.RequestHandlers.initHandlersFromConfig = created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7805 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7805 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 7806 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 7806 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.S= earchHandler [junit4:junit4] 2> 7807 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7807 T260 oasc.RequestHandlers.initHandlersFromConfig = created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7808 T260 oasc.RequestHandlers.initHandlersFromConfig = created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 7808 T260 oasc.RequestHandlers.initHandlersFromConfig = created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 7811 T260 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 7814 T260 oasc.SolrCore.initDeprecatedSupport WARNING = solrconfig.xml uses deprecated , Please update your co= nfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 7815 T260 oasc.SolrCore.initDeprecatedSupport WARNING = adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.= TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, = CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 7818 T260 oass.SolrIndexSearcher. Opening Search= er@5375e9db main [junit4:junit4] 2> 7819 T260 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 7819 T260 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 7820 T260 oashc.SpellCheckComponent.inform Initializin= g spell checkers [junit4:junit4] 2> 7825 T260 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 7893 T307 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@5375e9db main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 8060 T281 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 8073 T280 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 8073 T306 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 8073 T294 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 8399 T260 oasc.CoreContainer.register registering core= : collection1 [junit4:junit4] 2> 8399 T260 oasc.ZkController.register Register shard - = core:collection1 address:http://127.0.0.1:29766/solr shardId:shard1 [junit4:junit4] 2> 8404 T260 oasc.ZkController.register We are http://127= .0.0.1:29766/solr/collection1/ and leader is http://127.0.0.1:29763/solr/co= llection1/ [junit4:junit4] 2> 8404 T260 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:29766/solr [junit4:junit4] 2> 8405 T260 oasc.ZkController.checkRecovery Core needs t= o recover:collection1 [junit4:junit4] 2> 8405 T260 oasu.DefaultSolrCoreState.doRecovery Running= recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C25 name=3Dcollection1 org.apache.solr= .core.SolrCore@73ff0102 url=3Dhttp://127.0.0.1:29766/solr/collection1 node= =3D127.0.0.1:29766_solr [junit4:junit4] 2> 8412 T308 C25 P29766 oasc.RecoveryStrategy.run Startin= g recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 8412 T308 C25 P29766 oasc.RecoveryStrategy.doRecovery = ###### startupVersions=3D[] [junit4:junit4] 2> 8412 T260 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 8417 T308 C25 P29766 oasc.RecoveryStrategy.doRecovery = Attempting to PeerSync from http://127.0.0.1:29763/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 8418 T260 oass.SolrDispatchFilter.init user.dir=3D/usr= /home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 8418 T260 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 8419 T260 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 8424 T308 C25 P29766 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnectionsPerHost=3D20&maxConnection= s=3D10000&connTimeout=3D30000&socketTimeout=3D30000&retry=3Dfalse [junit4:junit4] 2> 8444 T308 C25 P29766 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:29766/solr START replicas=3D[http://1= 27.0.0.1:29763/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 8444 T308 C25 P29766 oasu.PeerSync.sync WARNING no fra= me of reference to tell of we've missed updates [junit4:junit4] 2> 8445 T308 C25 P29766 oasc.RecoveryStrategy.doRecovery = PeerSync Recovery was not successful - trying replication. core=3Dcollectio= n1 [junit4:junit4] 2> 8445 T308 C25 P29766 oasc.RecoveryStrategy.doRecovery = Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 8445 T308 C25 P29766 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnections=3D128&maxConnectionsPerHo= st=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C26 name=3Dcollection1 org.apache.solr= .core.SolrCore@674c8c6e url=3Dhttp://127.0.0.1:29763/solr/collection1 node= =3D127.0.0.1:29763_solr [junit4:junit4] 2> 8463 T286 C26 P29763 REQ /get {distrib=3Dfalse&qt=3D/g= et&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 8578 T281 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 8590 T280 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 8590 T294 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 8590 T306 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 8617 T260 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 8620 T260 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:29770 [junit4:junit4] 2> 8620 T260 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 8621 T260 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty3-1348352736437 [junit4:junit4] 2> 8621 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.SyncSliceTest-jetty3-1348352736437/' [junit4:junit4] 2> 8666 T260 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 8666 T260 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 8667 T260 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty3-1348352736437 [junit4:junit4] 2> 8667 T260 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSli= ceTest-jetty3-1348352736437/solr.xml [junit4:junit4] 2> 8668 T260 oasc.CoreContainer. New CoreContainer = 2032185280 [junit4:junit4] 2> 8668 T260 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSli= ceTest-jetty3-1348352736437/' [junit4:junit4] 2> 8669 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.S= yncSliceTest-jetty3-1348352736437/' [junit4:junit4] 2> 8695 T260 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 8713 T260 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:29756/solr [junit4:junit4] 2> 8714 T260 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:29756/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@35a21149 [junit4:junit4] 2> 8715 T319 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 8715 T260 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 8716 T319 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:29756, initiating sessio= n [junit4:junit4] 2> 8716 T262 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:29771 [junit4:junit4] 2> 8717 T262 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:29771 [junit4:junit4] 2> 8721 T264 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139f015dda50005 with negotiated timeout 5000 for client /140= .211.11.196:29771 [junit4:junit4] 2> 8721 T319 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:29756, session= id =3D 0x139f015dda50005, negotiated timeout =3D 5000 [junit4:junit4] 2> 8721 T320 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@35a21149 name:ZooKeeperConnectio= n Watcher:127.0.0.1:29756/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 8722 T260 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 8722 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50005 type:crea= te cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8729 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50005 type:crea= te cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8737 T260 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:29770_solr [junit4:junit4] 2> 8737 T266 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139f015dda50005 type:dele= te cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:29770_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:29770_solr [junit4:junit4] 2> 8747 T260 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:29770_solr [junit4:junit4] 2> 8755 T306 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 8755 T294 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 8755 T280 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 8756 T260 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 8757 T260 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 8757 T260 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 8758 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 8758 T260 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 8759 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 8759 T260 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 8759 T260 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 8760 T260 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 8760 T260 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 8783 T260 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 8790 T260 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.= cloud.SyncSliceTest-jetty3-1348352736437/collection1 [junit4:junit4] 2> 8790 T260 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4:junit4] 2> 8791 T260 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 8792 T260 oasc.ZkController.readConfigName Load collec= tion config from:/collections/collection1 [junit4:junit4] 2> 8793 T260 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.S= yncSliceTest-jetty3-1348352736437/collection1/' [junit4:junit4] 2> 8794 T260 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jet= ty3-1348352736437/collection1/lib/classes/' to classloader [junit4:junit4] 2> 8795 T260 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jet= ty3-1348352736437/collection1/lib/README' to classloader [junit4:junit4] 2> 8845 T260 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 8916 T260 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 9017 T260 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 9025 T260 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 9656 T260 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 9669 T260 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 9673 T260 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 9693 T260 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 9698 T260 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 9703 T260 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 9705 T260 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 9707 T260 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTes= t-jetty3-1348352736437/collection1/, dataDir=3D./org.apache.solr.cloud.Sync= SliceTest-1348352728005/jetty3/ [junit4:junit4] 2> 9707 T260 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@5a92668c [junit4:junit4] 2> 9708 T260 oasc.SolrCore.getNewIndexDir WARNING New ind= ex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTe= st-1348352728005/jetty3/index/ [junit4:junit4] 2> 9709 T260 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1348352728005= /jetty3/index' doesn't exist. Creating new index... [junit4:junit4] 2> 9710 T260 oasc.CachingDirectoryFactory.get return new = directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest= -1348352728005/jetty3/index forceNew:false [junit4:junit4] 2> 9732 T260 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty3/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 9732 T260 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1 [junit4:junit4] 2> 9733 T260 oasc.SolrCore.initWriters created xml: solr.= XMLResponseWriter [junit4:junit4] 2> 9735 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 9736 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe-allfields" [junit4:junit4] 2> 9736 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 9737 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "un= iq-fields" [junit4:junit4] 2> 9738 T260 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 9738 T260 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 9739 T260 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 9739 T260 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 9740 T260 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 9741 T260 oasc.RequestHandlers.initHandlersFromConfig = created dismax: solr.SearchHandler [junit4:junit4] 2> 9741 T260 oasc.RequestHandlers.initHandlersFromConfig = created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 9742 T260 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 9743 T260 oasc.RequestHandlers.initHandlersFromConfig = created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 9743 T260 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 9744 T260 oasc.RequestHandlers.initHandlersFromConfig = created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 9744 T260 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 9745 T260 oasc.RequestHandlers.initHandlersFromConfig = created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9746 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9746 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 9747 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 9748 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.S= earchHandler [junit4:junit4] 2> 9749 T260 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9749 T260 oasc.RequestHandlers.initHandlersFromConfig = created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9750 T260 oasc.RequestHandlers.initHandlersFromConfig = created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 9751 T260 oasc.RequestHandlers.initHandlersFromConfig = created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 9754 T260 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 9758 T260 oasc.SolrCore.initDeprecatedSupport WARNING = solrconfig.xml uses deprecated , Please update your co= nfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 9760 T260 oasc.SolrCore.initDeprecatedSupport WARNING = adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.= TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, = CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 9766 T260 oass.SolrIndexSearcher. Opening Search= er@28ab0e22 main [junit4:junit4] 2> 9767 T260 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 9767 T260 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 9768 T260 oashc.SpellCheckComponent.inform Initializin= g spell checkers [junit4:junit4] 2> 9784 T260 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 9853 T321 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@28ab0e22 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 10099 T281 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10112 T320 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10112 T294 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10112 T306 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10112 T280 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10361 T260 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 10361 T260 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:29770/solr shardId:shard1 [junit4:junit4] 2> 10366 T260 oasc.ZkController.register We are http://12= 7.0.0.1:29770/solr/collection1/ and leader is http://127.0.0.1:29763/solr/c= ollection1/ [junit4:junit4] 2> 10367 T260 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:29770/solr [junit4:junit4] 2> 10367 T260 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 10368 T260 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C27 name=3Dcollection1 org.apache.solr= .core.SolrCore@51e52bfd url=3Dhttp://127.0.0.1:29770/solr/collection1 node= =3D127.0.0.1:29770_solr [junit4:junit4] 2> 10369 T322 C27 P29770 oasc.RecoveryStrategy.run Starti= ng recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 10369 T322 C27 P29770 oasc.RecoveryStrategy.doRecovery= ###### startupVersions=3D[] [junit4:junit4] 2> 10369 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10371 T322 C27 P29770 oasc.RecoveryStrategy.doRecovery= Attempting to PeerSync from http://127.0.0.1:29763/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 10371 T322 C27 P29770 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:29770/solr START replicas=3D[http://= 127.0.0.1:29763/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 10371 T260 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/bui= ld/solr-core/test/J1 [junit4:junit4] 2> 10372 T322 C27 P29770 oasu.PeerSync.sync WARNING no fr= ame of reference to tell of we've missed updates [junit4:junit4] 2> 10372 T260 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 10373 T322 C27 P29770 oasc.RecoveryStrategy.doRecovery= PeerSync Recovery was not successful - trying replication. core=3Dcollecti= on1 [junit4:junit4] 2> 10373 T322 C27 P29770 oasc.RecoveryStrategy.doRecovery= Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 10373 T260 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 10373 T322 C27 P29770 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 10375 T286 C26 P29763 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10560 T260 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 10563 T260 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:29775 [junit4:junit4] 2> 10563 T260 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 10564 T260 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.SyncSliceTest-jetty4-1348352738396 [junit4:junit4] 2> 10564 T260 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.SyncSliceTest-jetty4-1348352738396/' [junit4:junit4] 2> 10608 T260 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 10609 T260 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 10609 T260 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.SyncSliceTest-jetty4-1348352738396 [junit4:junit4] 2> 10610 T260 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSl= iceTest-jetty4-1348352738396/solr.xml [junit4:junit4] 2> 10610 T260 oasc.CoreContainer. New CoreContainer= 744066701 [junit4:junit4] 2> 10611 T260 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSl= iceTest-jetty4-1348352738396/' [junit4:junit4] 2> 10611 T260 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.= SyncSliceTest-jetty4-1348352738396/' [junit4:junit4] 2> 10619 T281 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10635 T260 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 10644 T320 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10644 T280 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10644 T306 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10644 T294 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10650 T260 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:29756/solr [junit4:junit4] 2> 10651 T260 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:29756/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@31799ca4 [junit4:junit4] 2> 10652 T333 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 10652 T260 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 10653 T333 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:29756, initiating sessi= on [junit4:junit4] 2> 10653 T262 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:29776 [junit4:junit4] 2> 10654 T262 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:29776 [junit4:junit4] 2> 10732 T264 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139f015dda50006 with negotiated timeout 5000 for client /14= 0.211.11.196:29776 [junit4:junit4] 2> 10732 T333 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:29756, sessio= nid =3D 0x139f015dda50006, negotiated timeout =3D 5000 [junit4:junit4] 2> 10733 T334 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@31799ca4 name:ZooKeeperConnecti= on Watcher:127.0.0.1:29756/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 10733 T260 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 10734 T266 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139f015dda50006 type:cre= ate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 10743 T266 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139f015dda50006 type:cre= ate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 10751 T260 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:29775_solr [junit4:junit4] 2> 10751 T266 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139f015dda50006 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:29775_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:29775_solr [junit4:junit4] 2> 10778 T260 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:29775_solr [junit4:junit4] 2> 10809 T320 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 10809 T294 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 10810 T306 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 10810 T280 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 10811 T260 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 10811 T260 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 10812 T260 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 10812 T260 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 10813 T260 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 10813 T260 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 10813 T260 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 10814 T260 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 10814 T260 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 10815 T260 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 10863 T260 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 10869 T260 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.SyncSliceTest-jetty4-1348352738396/collection1 [junit4:junit4] 2> 10870 T260 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 10871 T260 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 10871 T260 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 10873 T260 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.= SyncSliceTest-jetty4-1348352738396/collection1/' [junit4:junit4] 2> 10874 T260 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trun= k-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-je= tty4-1348352738396/collection1/lib/README' to classloader [junit4:junit4] 2> 10875 T260 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trun= k-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-je= tty4-1348352738396/collection1/lib/classes/' to classloader [junit4:junit4] 2> 10926 T260 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 10995 T260 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 11096 T260 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 11107 T260 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> C25_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, colle= ction=3Dcollection1, node_name=3D127.0.0.1:29766_solr, base_url=3Dhttp://12= 7.0.0.1:29766/solr} [junit4:junit4] 2> 11472 T308 C25 P29766 oasc.RecoveryStrategy.doRecovery= Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 11472 T308 C25 P29766 oasu.UpdateLog.bufferUpdates Sta= rting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 11472 T308 C25 P29766 oasc.RecoveryStrategy.replicate = Attempting to replicate from http://127.0.0.1:29763/solr/collection1/. core= =3Dcollection1 [junit4:junit4] 2> 11472 T308 C25 P29766 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 11499 T289 C26 P29763 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D500&maxConnectionsPerH= ost=3D16 [junit4:junit4] 2> 11512 T289 C26 P29763 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 11517 T289 C26 P29763 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty1/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 11518 T289 C26 P29763 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 1 [junit4:junit4] 2> 11519 T289 C26 P29763 oass.SolrIndexSearcher. Op= ening Searcher@ca5bc81 main [junit4:junit4] 2> 11520 T289 C26 P29763 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 11522 T295 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@ca5bc81 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 11522 T289 C26 P29763 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version= =3D2} {commit=3D} 0 38 [junit4:junit4] 2> 11577 T308 C25 P29766 oash.ReplicationHandler.inform C= ommits will be reserved for 10000 [junit4:junit4] 2> 11578 T308 C25 P29766 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:connTimeout=3D5000&socketTimeout=3D2000= 0&allowCompression=3Dfalse&maxConnections=3D10000&maxConnectionsPerHost=3D1= 0000 [junit4:junit4] 2> 11599 T308 C25 P29766 oash.SnapPuller. No value= set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 11602 T290 C26 P29763 oash.ReplicationHandler.inform C= ommits will be reserved for 10000 [junit4:junit4] 2> 11602 T290 C26 P29763 REQ /replication {command=3Dinde= xversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 11607 T308 C25 P29766 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty2/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 11608 T308 C25 P29766 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 1 [junit4:junit4] 2> 11608 T308 C25 P29766 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 11621 T308 C25 P29766 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty2/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty2/index,segFN=3Dsegments_2,gene= ration=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 11621 T308 C25 P29766 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2 [junit4:junit4] 2> 11628 T308 C25 P29766 oass.SolrIndexSearcher. Op= ening Searcher@3c7c5dfc main [junit4:junit4] 2> 11628 T308 C25 P29766 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 11630 T307 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@3c7c5dfc main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 11631 T308 C25 P29766 oasc.RecoveryStrategy.replay No = replay needed. core=3Dcollection1 [junit4:junit4] 2> 11631 T308 C25 P29766 oasc.RecoveryStrategy.doRecovery= Replication Recovery was successful - registering as Active. core=3Dcollec= tion1 [junit4:junit4] 2> 11640 T308 C25 P29766 oasc.RecoveryStrategy.doRecovery= Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 11659 T281 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11683 T294 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 11683 T320 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 11683 T334 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 11683 T306 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 11683 T280 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 11811 T260 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 11823 T260 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 11827 T260 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 11845 T260 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 11856 T260 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 11860 T260 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 11864 T260 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 11866 T260 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-t= runk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTe= st-jetty4-1348352738396/collection1/, dataDir=3D./org.apache.solr.cloud.Syn= cSliceTest-1348352728005/jetty4/ [junit4:junit4] 2> 11867 T260 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@5a92668c [junit4:junit4] 2> 11868 T260 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceT= est-1348352728005/jetty4/index/ [junit4:junit4] 2> 11869 T260 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-134835272800= 5/jetty4/index' doesn't exist. Creating new index... [junit4:junit4] 2> 11870 T260 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTes= t-1348352728005/jetty4/index forceNew:false [junit4:junit4] 2> 11876 T260 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty4/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 11876 T260 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 11877 T260 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 11880 T260 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 11880 T260 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 11881 T260 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 11881 T260 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 11882 T260 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 11883 T260 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 11883 T260 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 11884 T260 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 11885 T260 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 11885 T260 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 11886 T260 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 11887 T260 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 11888 T260 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 11888 T260 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 11889 T260 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 11889 T260 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 11890 T260 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 11891 T260 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 11891 T260 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 11892 T260 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 11893 T260 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 11894 T260 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 11894 T260 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 11895 T260 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 11896 T260 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 11900 T260 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 11907 T260 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 11908 T260 oasc.SolrCore.initDeprecatedSupport WARNING= adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS= .TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT,= CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 11912 T260 oass.SolrIndexSearcher. Opening Searc= her@458a2ccf main [junit4:junit4] 2> 11914 T260 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 11914 T260 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 11915 T260 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 11924 T260 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 11984 T335 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@458a2ccf main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 12194 T281 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12209 T320 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12209 T280 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12209 T306 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12209 T334 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12209 T294 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12498 T260 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 12498 T260 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:29775/solr shardId:shard1 [junit4:junit4] 2> 12507 T260 oasc.ZkController.register We are http://12= 7.0.0.1:29775/solr/collection1/ and leader is http://127.0.0.1:29763/solr/c= ollection1/ [junit4:junit4] 2> 12508 T260 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:29775/solr [junit4:junit4] 2> 12508 T260 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 12508 T260 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C28 name=3Dcollection1 org.apache.solr= .core.SolrCore@224b1370 url=3Dhttp://127.0.0.1:29775/solr/collection1 node= =3D127.0.0.1:29775_solr [junit4:junit4] 2> 12509 T336 C28 P29775 oasc.RecoveryStrategy.run Starti= ng recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 12510 T336 C28 P29775 oasc.RecoveryStrategy.doRecovery= ###### startupVersions=3D[] [junit4:junit4] 2> 12510 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12516 T336 C28 P29775 oasc.RecoveryStrategy.doRecovery= Attempting to PeerSync from http://127.0.0.1:29763/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 12516 T336 C28 P29775 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:29775/solr START replicas=3D[http://= 127.0.0.1:29763/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 12516 T260 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/bui= ld/solr-core/test/J1 [junit4:junit4] 2> 12517 T336 C28 P29775 oasu.PeerSync.sync WARNING no fr= ame of reference to tell of we've missed updates [junit4:junit4] 2> 12517 T260 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 12517 T336 C28 P29775 oasc.RecoveryStrategy.doRecovery= PeerSync Recovery was not successful - trying replication. core=3Dcollecti= on1 [junit4:junit4] 2> 12518 T336 C28 P29775 oasc.RecoveryStrategy.doRecovery= Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 12518 T260 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 12518 T336 C28 P29775 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 12519 T286 C26 P29763 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12544 T260 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:29756/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@109c4289 [junit4:junit4] 2> 12545 T338 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 12545 T260 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 12546 T338 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:29756, initiating sessi= on [junit4:junit4] 2> 12546 T262 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:29781 [junit4:junit4] 2> 12547 T262 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:29781 [junit4:junit4] 2> 12562 T264 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139f015dda50007 with negotiated timeout 10000 for client /1= 40.211.11.196:29781 [junit4:junit4] 2> 12563 T338 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:29756, sessio= nid =3D 0x139f015dda50007, negotiated timeout =3D 10000 [junit4:junit4] 2> 12563 T339 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@109c4289 name:ZooKeeperConnecti= on Watcher:127.0.0.1:29756/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 12563 T260 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 12565 T260 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 12567 T260 oasc.ChaosMonkey.monkeyLog monkey: init - e= xpire sessions:true cause connection loss:true [junit4:junit4] 2> 12568 T260 oascsi.HttpClientUtil.createClient Creating= new http client, config: [junit4:junit4] 2> 12585 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12588 T260 oasc.AbstractFullDistribZkTestBase.waitForT= hingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt [junit4:junit4] 2> 12588 T260 oasc.AbstractDistribZkTestBase.waitForRecov= eriesToFinish Wait for recoveries to finish - collection: collection1 failO= nTimeout:true timeout (sec):15 [junit4:junit4] 2> 12590 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12715 T281 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12725 T320 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12725 T339 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12725 T334 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12725 T306 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12725 T294 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 12725 T280 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> C27_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, colle= ction=3Dcollection1, node_name=3D127.0.0.1:29770_solr, base_url=3Dhttp://12= 7.0.0.1:29770/solr} [junit4:junit4] 2> 13398 T322 C27 P29770 oasc.RecoveryStrategy.doRecovery= Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 13398 T322 C27 P29770 oasu.UpdateLog.bufferUpdates Sta= rting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 13399 T322 C27 P29770 oasc.RecoveryStrategy.replicate = Attempting to replicate from http://127.0.0.1:29763/solr/collection1/. core= =3Dcollection1 [junit4:junit4] 2> 13399 T322 C27 P29770 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 13411 T292 C26 P29763 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 13414 T292 C26 P29763 oass.SolrIndexSearcher. Op= ening Searcher@41480ec1 main [junit4:junit4] 2> 13415 T292 C26 P29763 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 13416 T295 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@41480ec1 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 13417 T292 C26 P29763 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version= =3D2} {commit=3D} 0 7 [junit4:junit4] 2> 13418 T322 C27 P29770 oash.ReplicationHandler.inform C= ommits will be reserved for 10000 [junit4:junit4] 2> 13418 T322 C27 P29770 oash.SnapPuller. No value= set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 13421 T290 C26 P29763 REQ /replication {command=3Dinde= xversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 13427 T322 C27 P29770 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty3/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 13427 T322 C27 P29770 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 1 [junit4:junit4] 2> 13428 T322 C27 P29770 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 13446 T322 C27 P29770 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty3/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty3/index,segFN=3Dsegments_2,gene= ration=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 13446 T322 C27 P29770 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2 [junit4:junit4] 2> 13449 T322 C27 P29770 oass.SolrIndexSearcher. Op= ening Searcher@5a2a7673 main [junit4:junit4] 2> 13450 T322 C27 P29770 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 13452 T321 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@5a2a7673 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 13452 T322 C27 P29770 oasc.RecoveryStrategy.replay No = replay needed. core=3Dcollection1 [junit4:junit4] 2> 13452 T322 C27 P29770 oasc.RecoveryStrategy.doRecovery= Replication Recovery was successful - registering as Active. core=3Dcollec= tion1 [junit4:junit4] 2> 13454 T322 C27 P29770 oasc.RecoveryStrategy.doRecovery= Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 13592 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13733 T281 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13762 T339 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 13763 T306 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 13763 T334 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 13763 T280 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 13762 T320 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 13762 T294 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 14595 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> C28_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, colle= ction=3Dcollection1, node_name=3D127.0.0.1:29775_solr, base_url=3Dhttp://12= 7.0.0.1:29775/solr} [junit4:junit4] 2> 15543 T336 C28 P29775 oasc.RecoveryStrategy.doRecovery= Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 15543 T336 C28 P29775 oasu.UpdateLog.bufferUpdates Sta= rting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 15543 T336 C28 P29775 oasc.RecoveryStrategy.replicate = Attempting to replicate from http://127.0.0.1:29763/solr/collection1/. core= =3Dcollection1 [junit4:junit4] 2> 15543 T336 C28 P29775 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 15561 T287 C26 P29763 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15564 T287 C26 P29763 oass.SolrIndexSearcher. Op= ening Searcher@73927025 main [junit4:junit4] 2> 15565 T287 C26 P29763 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 15567 T295 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@73927025 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 15567 T287 C26 P29763 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version= =3D2} {commit=3D} 0 6 [junit4:junit4] 2> 15568 T336 C28 P29775 oash.ReplicationHandler.inform C= ommits will be reserved for 10000 [junit4:junit4] 2> 15568 T336 C28 P29775 oash.SnapPuller. No value= set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 15571 T290 C26 P29763 REQ /replication {command=3Dinde= xversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 15576 T336 C28 P29775 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty4/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 15576 T336 C28 P29775 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 1 [junit4:junit4] 2> 15577 T336 C28 P29775 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15581 T336 C28 P29775 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty4/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty4/index,segFN=3Dsegments_2,gene= ration=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 15581 T336 C28 P29775 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2 [junit4:junit4] 2> 15585 T336 C28 P29775 oass.SolrIndexSearcher. Op= ening Searcher@672f151d main [junit4:junit4] 2> 15585 T336 C28 P29775 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 15587 T335 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@672f151d main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 15587 T336 C28 P29775 oasc.RecoveryStrategy.replay No = replay needed. core=3Dcollection1 [junit4:junit4] 2> 15588 T336 C28 P29775 oasc.RecoveryStrategy.doRecovery= Replication Recovery was successful - registering as Active. core=3Dcollec= tion1 [junit4:junit4] 2> 15590 T336 C28 P29775 oasc.RecoveryStrategy.doRecovery= Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 15598 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15779 T281 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15863 T339 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15864 T294 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15864 T306 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15864 T334 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15864 T280 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15864 T320 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 16600 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> ASYNC NEW_CORE C29 name=3Dcollection1 org.apache.solr= .core.SolrCore@2c6749fe url=3Dhttp://127.0.0.1:29760/solr/collection1 node= =3D127.0.0.1:29760_solr [junit4:junit4] 2> 16613 T272 C29 P29760 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16618 T272 C29 P29760 oasc.SolrDeletionPolicy.onInit S= olrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/control/data/index,segFN=3Dsegments_= 1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 16618 T272 C29 P29760 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 1 [junit4:junit4] 2> 16620 T272 C29 P29760 oass.SolrIndexSearcher. Op= ening Searcher@40829a81 main [junit4:junit4] 2> 16620 T272 C29 P29760 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 16622 T283 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@40829a81 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 16623 T272 C29 P29760 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 11 [junit4:junit4] 2> 16623 T260 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:29756/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@3e6dac3d [junit4:junit4] 2> 16624 T340 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:29756 [junit4:junit4] 2> 16624 T260 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 16625 T340 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:29756, initiating sessi= on [junit4:junit4] 2> 16625 T262 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:29787 [junit4:junit4] 2> 16626 T262 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:29787 [junit4:junit4] 2> 16628 T264 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139f015dda50008 with negotiated timeout 10000 for client /1= 40.211.11.196:29787 [junit4:junit4] 2> 16628 T340 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:29756, sessio= nid =3D 0x139f015dda50008, negotiated timeout =3D 10000 [junit4:junit4] 2> 16628 T341 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@3e6dac3d name:ZooKeeperConnecti= on Watcher:127.0.0.1:29756/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 16629 T260 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 16630 T260 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 16633 T288 C26 P29763 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16637 T288 C26 P29763 oass.SolrIndexSearcher. Op= ening Searcher@a8af4b4 main [junit4:junit4] 2> 16638 T288 C26 P29763 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 16639 T295 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@a8af4b4 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 16663 T288 C26 P29763 oasu.SolrCmdDistributor.distribC= ommit Distrib commit to:[StdNode: http://127.0.0.1:29766/solr/collection1/,= StdNode: http://127.0.0.1:29770/solr/collection1/, StdNode: http://127.0.0= .1:29775/solr/collection1/] [junit4:junit4] 2> C27_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collectio= n=3Dcollection1, node_name=3D127.0.0.1:29770_solr, base_url=3Dhttp://127.0.= 0.1:29770/solr} [junit4:junit4] 2> 16698 T312 C27 P29770 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> C28_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collectio= n=3Dcollection1, node_name=3D127.0.0.1:29775_solr, base_url=3Dhttp://127.0.= 0.1:29775/solr} [junit4:junit4] 2> 16698 T326 C28 P29775 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> C25_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collectio= n=3Dcollection1, node_name=3D127.0.0.1:29766_solr, base_url=3Dhttp://127.0.= 0.1:29766/solr} [junit4:junit4] 2> 16698 T298 C25 P29766 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16701 T326 C28 P29775 oass.SolrIndexSearcher. Op= ening Searcher@49f4493e main [junit4:junit4] 2> 16702 T326 C28 P29775 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 16702 T312 C27 P29770 oass.SolrIndexSearcher. Op= ening Searcher@1b1f4efe main [junit4:junit4] 2> 16703 T312 C27 P29770 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 16703 T335 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@49f4493e main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 16704 T298 C25 P29766 oass.SolrIndexSearcher. Op= ening Searcher@c4437d3 main [junit4:junit4] 2> 16704 T326 C28 P29775 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&soft= Commit=3Dfalse&version=3D2} {commit=3D} 0 6 [junit4:junit4] 2> 16704 T298 C25 P29766 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 16704 T321 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1b1f4efe main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 16705 T312 C27 P29770 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&soft= Commit=3Dfalse&version=3D2} {commit=3D} 0 8 [junit4:junit4] 2> 16706 T307 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@c4437d3 main{StandardDirectoryReader(seg= ments_2:2)} [junit4:junit4] 2> 16706 T298 C25 P29766 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&soft= Commit=3Dfalse&version=3D2} {commit=3D} 0 8 [junit4:junit4] 2> 16707 T288 C26 P29763 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 74 [junit4:junit4] 2> 16708 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 16711 T289 C26 P29763 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 16714 T299 C25 P29766 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16716 T313 C27 P29770 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16719 T327 C28 P29775 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18790 T272 C29 P29760 oass.SolrIndexSearcher. Op= ening Searcher@13197df2 realtime [junit4:junit4] 2> 18794 T272 C29 P29760 /update {wt=3Djavabin&version=3D= 2} {deleteByQuery=3D*:* (-1413850329755156480)} 0 72 [junit4:junit4] 2> 18799 T288 C26 P29763 oass.SolrIndexSearcher. Op= ening Searcher@2c578bfa realtime [junit4:junit4] 2> 18808 T326 C28 P29775 oass.SolrIndexSearcher. Op= ening Searcher@2609990d realtime [junit4:junit4] 2> 18808 T312 C27 P29770 oass.SolrIndexSearcher. Op= ening Searcher@4194e3ee realtime [junit4:junit4] 2> 18809 T326 C28 P29775 REQ /update {update.distrib=3DFR= OMLEADER&_version_=3D-1413850329826459648&update.from=3Dhttp://127.0.0.1:29= 763/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 18809 T312 C27 P29770 REQ /update {update.distrib=3DFR= OMLEADER&_version_=3D-1413850329826459648&update.from=3Dhttp://127.0.0.1:29= 763/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 18809 T298 C25 P29766 oass.SolrIndexSearcher. Op= ening Searcher@6a62deab realtime [junit4:junit4] 2> 18810 T298 C25 P29766 REQ /update {update.distrib=3DFR= OMLEADER&_version_=3D-1413850329826459648&update.from=3Dhttp://127.0.0.1:29= 763/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 18810 T288 C26 P29763 /update {wt=3Djavabin&version=3D= 2} {deleteByQuery=3D*:* (-1413850329826459648)} 0 13 [junit4:junit4] 2> 18821 T272 C29 P29760 /update {wt=3Djavabin&version=3D= 2} {add=3D[0 (1413850329848479744)]} 0 8 [junit4:junit4] 2> 18842 T312 C27 P29770 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 18842 T326 C28 P29775 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 18843 T298 C25 P29766 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 18843 T288 C26 P29763 /update {wt=3Djavabin&version=3D= 2} {add=3D[0 (1413850329854771200)]} 0 19 [junit4:junit4] 2> 18847 T272 C29 P29760 /update {wt=3Djavabin&version=3D= 2} {add=3D[1 (1413850329877839872)]} 0 1 [junit4:junit4] 2> 18854 T312 C27 P29770 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18855 T298 C25 P29766 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 18855 T326 C28 P29775 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18856 T288 C26 P29763 /update {wt=3Djavabin&version=3D= 2} {add=3D[1 (1413850329882034176)]} 0 6 [junit4:junit4] 2> 18860 T272 C29 P29760 /update {wt=3Djavabin&version=3D= 2} {add=3D[2 (1413850329891471360)]} 0 1 [junit4:junit4] 2> 18867 T312 C27 P29770 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&test.distr= ib.skip.servers=3Dhttp://127.0.0.1:29766/solr/collection1/&wt=3Djavabin&ver= sion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18867 T326 C28 P29775 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&test.distr= ib.skip.servers=3Dhttp://127.0.0.1:29766/solr/collection1/&wt=3Djavabin&ver= sion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18868 T288 C26 P29763 /update {test.distrib.skip.serve= rs=3Dhttp://127.0.0.1:29766/solr/collection1/&wt=3Djavabin&version=3D2} {ad= d=3D[2 (1413850329895665664)]} 0 5 [junit4:junit4] 2> 18872 T272 C29 P29760 /update {wt=3Djavabin&version=3D= 2} {add=3D[3 (1413850329904054272)]} 0 1 [junit4:junit4] 2> 18879 T326 C28 P29775 REQ /update {distrib.from=3Dhttp= ://127.0.0.1:29763/solr/collection1/&update.distrib=3DFROMLEADER&test.distr= ib.skip.servers=3Dhttp://127.0.0.1:29766/solr/collection1/&test.distrib.ski= p.servers=3Dhttp://127.0.0.1:29770/solr/collection1/&wt=3Djavabin&version= =3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18880 T288 C26 P29763 /update {test.distrib.skip.serve= rs=3Dhttp://127.0.0.1:29766/solr/collection1/&test.distrib.skip.servers=3Dh= ttp://127.0.0.1:29770/solr/collection1/&wt=3Djavabin&version=3D2} {add=3D[3= (1413850329908248576)]} 0 5 [junit4:junit4] 2> 18883 T272 C29 P29760 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18998 T272 C29 P29760 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/control/data/index,segFN=3Dsegments_= 1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/control/data/index,segFN=3Dsegments_= 2,generation=3D2,filenames=3D[_0_Lucene40_0.tim, _0.fnm, _0_Pulsing40_0.tim= , _0_nrm.cfs, _0_Pulsing40_0.prx, _0_Lucene40_0.prx, _0_Pulsing40_1.prx, _0= _Pulsing40_0.frq, _0_Lucene40_0.tip, _0_Lucene40_0.frq, _0_Pulsing40_1.tip,= _0_Pulsing40_1.frq, _0_Pulsing40_1.tim, _0_SimpleText_0.pst, _0_nrm.cfe, _= 0_Pulsing40_0.tip, segments_2, _0.fdx, _0.si, _0.fdt] [junit4:junit4] 2> 18999 T272 C29 P29760 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2 [junit4:junit4] 2> 19010 T272 C29 P29760 oass.SolrIndexSearcher. Op= ening Searcher@68fa8cf9 main [junit4:junit4] 2> 19011 T272 C29 P29760 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 19012 T283 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@68fa8cf9 main{StandardDirectoryReader(se= gments_2:3:nrt _0(5.0):C4)} [junit4:junit4] 2> 19013 T272 C29 P29760 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 130 [junit4:junit4] 2> 19016 T288 C26 P29763 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19145 T288 C26 P29763 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty1/index,segFN=3Dsegments_1,gene= ration=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty1/index,segFN=3Dsegments_2,gene= ration=3D2,filenames=3D[_0_Lucene40_0.tim, _0.fnm, _0_Pulsing40_0.tim, _0_n= rm.cfs, _0_Pulsing40_0.prx, _0_Lucene40_0.prx, _0_Pulsing40_1.prx, _0_Pulsi= ng40_0.frq, _0_Lucene40_0.tip, _0_Lucene40_0.frq, _0_Pulsing40_1.tip, _0_Pu= lsing40_1.frq, _0_Pulsing40_1.tim, _0_SimpleText_0.pst, _0_nrm.cfe, _0_Puls= ing40_0.tip, segments_2, _0.fdx, _0.si, _0.fdt] [junit4:junit4] 2> 19146 T288 C26 P29763 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 2 [junit4:junit4] 2> 19156 T288 C26 P29763 oass.SolrIndexSearcher. Op= ening Searcher@5c59782b main [junit4:junit4] 2> 19157 T288 C26 P29763 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 19158 T295 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@5c59782b main{StandardDirectoryReader(se= gments_2:3:nrt _0(5.0):C4)} [junit4:junit4] 2> 19159 T288 C26 P29763 oasu.SolrCmdDistributor.distribC= ommit Distrib commit to:[StdNode: http://127.0.0.1:29766/solr/collection1/,= StdNode: http://127.0.0.1:29770/solr/collection1/, StdNode: http://127.0.0= .1:29775/solr/collection1/] [junit4:junit4] 2> 19162 T312 C27 P29770 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19162 T326 C28 P29775 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19162 T298 C25 P29766 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19277 T312 C27 P29770 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty3/index,segFN=3Dsegments_2,gene= ration=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty3/index,segFN=3Dsegments_3,gene= ration=3D3,filenames=3D[_0_Lucene40_0.tim, _0.fnm, _0_Pulsing40_0.tim, _0_n= rm.cfs, _0_Pulsing40_0.prx, _0_Lucene40_0.prx, _0_Pulsing40_1.prx, _0_Pulsi= ng40_0.frq, _0_Lucene40_0.tip, _0_Lucene40_0.frq, _0_Pulsing40_1.tip, _0_Pu= lsing40_1.frq, _0_Pulsing40_1.tim, _0_SimpleText_0.pst, _0_nrm.cfe, _0_Puls= ing40_0.tip, _0.fdx, _0.si, _0.fdt, segments_3] [junit4:junit4] 2> 19277 T312 C27 P29770 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 3 [junit4:junit4] 2> 19286 T312 C27 P29770 oass.SolrIndexSearcher. Op= ening Searcher@2d7e51a9 main [junit4:junit4] 2> 19287 T312 C27 P29770 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 19289 T321 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@2d7e51a9 main{StandardDirectoryReader(se= gments_3:4:nrt _0(5.0):C3)} [junit4:junit4] 2> 19289 T312 C27 P29770 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&soft= Commit=3Dfalse&version=3D2} {commit=3D} 0 127 [junit4:junit4] 2> 19290 T298 C25 P29766 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty2/index,segFN=3Dsegments_2,gene= ration=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty2/index,segFN=3Dsegments_3,gene= ration=3D3,filenames=3D[_0_Lucene40_0.tim, _0.fnm, _0_Pulsing40_0.tim, _0_n= rm.cfs, _0_Pulsing40_0.prx, _0_Lucene40_0.prx, _0_Pulsing40_1.prx, _0_Pulsi= ng40_0.frq, _0_Lucene40_0.tip, _0_Lucene40_0.frq, _0_Pulsing40_1.tip, _0_Pu= lsing40_1.frq, _0_Pulsing40_1.tim, _0_SimpleText_0.pst, _0_nrm.cfe, _0_Puls= ing40_0.tip, _0.fdx, _0.si, _0.fdt, segments_3] [junit4:junit4] 2> 19290 T298 C25 P29766 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 3 [junit4:junit4] 2> 19299 T298 C25 P29766 oass.SolrIndexSearcher. Op= ening Searcher@511fc987 main [junit4:junit4] 2> 19300 T298 C25 P29766 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 19301 T326 C28 P29775 oasc.SolrDeletionPolicy.onCommit= SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty4/index,segFN=3Dsegments_2,gene= ration=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.= solr.cloud.SyncSliceTest-1348352728005/jetty4/index,segFN=3Dsegments_3,gene= ration=3D3,filenames=3D[_0_Lucene40_0.tim, _0.fnm, _0_Pulsing40_0.tim, _0_n= rm.cfs, _0_Pulsing40_0.prx, _0_Lucene40_0.prx, _0_Pulsing40_1.prx, _0_Pulsi= ng40_0.frq, _0_Lucene40_0.tip, _0_Lucene40_0.frq, _0_Pulsing40_1.tip, _0_Pu= lsing40_1.frq, _0_Pulsing40_1.tim, _0_SimpleText_0.pst, _0_nrm.cfe, _0_Puls= ing40_0.tip, _0.fdx, _0.si, _0.fdt, segments_3] [junit4:junit4] 2> 19301 T326 C28 P29775 oasc.SolrDeletionPolicy.updateCo= mmits newest commit =3D 3 [junit4:junit4] 2> 19302 T307 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@511fc987 main{StandardDirectoryReader(se= gments_3:4:nrt _0(5.0):C2)} [junit4:junit4] 2> 19302 T298 C25 P29766 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&soft= Commit=3Dfalse&version=3D2} {commit=3D} 0 140 [junit4:junit4] 2> 19312 T326 C28 P29775 oass.SolrIndexSearcher. Op= ening Searcher@c25c793 main [junit4:junit4] 2> 19313 T326 C28 P29775 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 19314 T335 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@c25c793 main{StandardDirectoryReader(seg= ments_3:4:nrt _0(5.0):C4)} [junit4:junit4] 2> 19315 T326 C28 P29775 /update {waitSearcher=3Dtrue&com= mit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&soft= Commit=3Dfalse&version=3D2} {commit=3D} 0 153 [junit4:junit4] 2> 19315 T288 C26 P29763 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 299 [junit4:junit4] 2> 19316 T260 oasc.AbstractDistribZkTestBase.waitForRecov= eriesToFinish Wait for recoveries to finish - collection: collection1 failO= nTimeout:true timeout (sec):1800 [junit4:junit4] 2> 19317 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> check const of shard1 [junit4:junit4] 2> client0 [junit4:junit4] 2> PROPS:127.0.0.1:29763_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:29763_solr","base_url":"http://127.0.0.1:29763= /solr","leader":"true"} [junit4:junit4] 2> 19320 T289 C26 P29763 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client1 [junit4:junit4] 2> PROPS:127.0.0.1:29766_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:29766_solr","base_url":"http://127.0.0.1:29766= /solr"} [junit4:junit4] 2> 19324 T299 C25 P29766 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D2 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:2 [junit4:junit4] 2>=20 [junit4:junit4] 2> ######shard1 is not consistent. Got 4 from http://127= .0.0.1:29763/solr/collection1lastClient and got 2 from http://127.0.0.1:297= 66/solr/collection1 [junit4:junit4] 2> 19328 T289 C26 P29763 REQ /select {fl=3Did,_version_&s= ort=3Did+asc&q=3D*:*&distrib=3Dfalse&wt=3Djavabin&rows=3D1000&version=3D2} = hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 19330 T299 C25 P29766 REQ /select {fl=3Did,_version_&s= ort=3Did+asc&q=3D*:*&distrib=3Dfalse&wt=3Djavabin&rows=3D1000&version=3D2} = hits=3D2 status=3D0 QTime=3D0=20 [junit4:junit4] 2> ######http://127.0.0.1:29763/solr/collection1: {numFou= nd=3D4,start=3D0,docs=3D[SolrDocument{id=3D0, _version_=3D14138503298547712= 00}, SolrDocument{id=3D1, _version_=3D1413850329882034176}, SolrDocument{id= =3D2, _version_=3D1413850329895665664}, SolrDocument{id=3D3, _version_=3D14= 13850329908248576}]} [junit4:junit4] 2> ######http://127.0.0.1:29766/solr/collection1: {numFou= nd=3D2,start=3D0,docs=3D[SolrDocument{id=3D0, _version_=3D14138503298547712= 00}, SolrDocument{id=3D1, _version_=3D1413850329882034176}]} [junit4:junit4] 2> ###### sizes=3D4,2 [junit4:junit4] 2> ###### Only in http://127.0.0.1:29763/solr/collection1= : [{id=3D3, _version_=3D1413850329908248576}, {id=3D2, _version_=3D14138503= 29895665664}] [junit4:junit4] 2> client2 [junit4:junit4] 2> PROPS:127.0.0.1:29770_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:29770_solr","base_url":"http://127.0.0.1:29770= /solr"} [junit4:junit4] 2> 19334 T313 C27 P29770 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D3 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:3 [junit4:junit4] 2>=20 [junit4:junit4] 2> client3 [junit4:junit4] 2> PROPS:127.0.0.1:29775_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:29775_solr","base_url":"http://127.0.0.1:29775= /solr"} [junit4:junit4] 2> 19338 T327 C28 P29775 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> 19345 T260 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 19374 T314 oasha.CollectionsHandler.handleSyncShardAct= ion Syncing shard : action=3DSYNCSHARD&collection=3Dcollection1&shard=3Dsha= rd1&wt=3Djavabin&version=3D2 [junit4:junit4] 2> 19375 T314 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 19396 T292 oasha.CoreAdminHandler.handleRequestSyncAct= ion I have been requested to sync up my shard [junit4:junit4] 2> 19397 T292 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:29763/solr/collection1/ [junit4:junit4] 2> 19397 T292 oasu.PeerSync.sync PeerSync: core=3Dcollect= ion1 url=3Dhttp://127.0.0.1:29763/solr START replicas=3D[http://127.0.0.1:2= 9766/solr/collection1/, http://127.0.0.1:29770/solr/collection1/, http://12= 7.0.0.1:29775/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 19418 T315 C27 P29770 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D18=20 [junit4:junit4] 2> 19418 T328 C28 P29775 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D18=20 [junit4:junit4] 2> 19418 T300 C25 P29766 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D18=20 [junit4:junit4] 2> 19419 T292 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:29763/solr Received 4 versions from = 127.0.0.1:29770/solr/collection1/ [junit4:junit4] 2> 19419 T292 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:29763/solr Our versions are newer. o= urLowThreshold=3D1413850329826459648 otherHigh=3D1413850329895665664 [junit4:junit4] 2> 19419 T292 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:29763/solr Received 5 versions from = 127.0.0.1:29775/solr/collection1/ [junit4:junit4] 2> 19420 T292 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:29763/solr Our versions are newer. o= urLowThreshold=3D1413850329826459648 otherHigh=3D1413850329895665664 [junit4:junit4] 2> 19420 T292 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:29763/solr Received 3 versions from = 127.0.0.1:29766/solr/collection1/ [junit4:junit4] 2> 19420 T292 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:29763/solr Our versions are newer. o= urLowThreshold=3D1413850329826459648 otherHigh=3D1413850329882034176 [junit4:junit4] 2> 19421 T292 oasu.PeerSync.sync PeerSync: core=3Dcollect= ion1 url=3Dhttp://127.0.0.1:29763/solr DONE. sync succeeded [junit4:junit4] 2> 19421 T292 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 19421 T292 oasc.SyncStrategy.syncToMe http://127.0.0.1= :29763/solr/collection1/: try and ask http://127.0.0.1:29766/solr/collectio= n1/ to sync [junit4:junit4] 2> 19422 T292 oasc.SyncStrategy.syncToMe http://127.0.0.1= :29763/solr/collection1/: try and ask http://127.0.0.1:29770/solr/collectio= n1/ to sync [junit4:junit4] 2> 19423 T292 oasc.SyncStrategy.syncToMe http://127.0.0.1= :29763/solr/collection1/: try and ask http://127.0.0.1:29775/solr/collectio= n1/ to sync [junit4:junit4] 2> 19424 T301 C25 P29766 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:29766/solr START replicas=3D[http://= 127.0.0.1:29763/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 19425 T316 C27 P29770 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:29770/solr START replicas=3D[http://= 127.0.0.1:29763/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 19425 T329 C28 P29775 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:29775/solr START replicas=3D[http://= 127.0.0.1:29763/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 19427 T291 C26 P29763 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 19428 T286 C26 P29763 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 19428 T287 C26 P29763 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 19428 T316 C27 P29770 oasu.PeerSync.handleVersions Pee= rSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:29770/solr Received 5 ver= sions from 127.0.0.1:29763/solr/collection1/ [junit4:junit4] 2> 19429 T329 C28 P29775 oasu.PeerSync.handleVersions Pee= rSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:29775/solr Received 5 ver= sions from 127.0.0.1:29763/solr/collection1/ [junit4:junit4] 2> 19429 T316 C27 P29770 oasu.PeerSync.requestUpdates Pee= rSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:29770/solr Requesting upda= tes from 127.0.0.1:29763/solr/collection1/n=3D1 versions=3D[141385032990824= 8576] [junit4:junit4] 2> 19429 T329 C28 P29775 oasu.PeerSync.handleVersions Pee= rSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:29775/solr Our versions a= re newer. ourLowThreshold=3D1413850329826459648 otherHigh=3D141385032989566= 5664 [junit4:junit4] 2> 19429 T301 C25 P29766 oasu.PeerSync.handleVersions Pee= rSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:29766/solr Received 5 ver= sions from 127.0.0.1:29763/solr/collection1/ [junit4:junit4] 2> 19429 T329 C28 P29775 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:29775/solr DONE. sync succeeded [junit4:junit4] 2> 19430 T301 C25 P29766 oasu.PeerSync.requestUpdates Pee= rSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:29766/solr Requesting upda= tes from 127.0.0.1:29763/solr/collection1/n=3D2 versions=3D[141385032990824= 8576, 1413850329895665664] [junit4:junit4] 2> 19430 T329 C28 P29775 REQ /get {sync=3Dhttp://127.0.0.= 1:29763/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt=3D= javabin&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 19431 T292 oasc.SyncStrategy.syncToMe http://127.0.0.1= :29763/solr/collection1/: sync completed with http://127.0.0.1:29775/solr/= collection1/ [junit4:junit4] 2> 19431 T286 C26 P29763 REQ /get {distrib=3Dfalse&getUpd= ates=3D1413850329908248576&qt=3D/get&wt=3Djavabin&version=3D2} status=3D0 Q= Time=3D0=20 [junit4:junit4] 2> 19432 T287 C26 P29763 REQ /get {distrib=3Dfalse&getUpd= ates=3D1413850329908248576,1413850329895665664&qt=3D/get&wt=3Djavabin&versi= on=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 19438 T316 C27 P29770 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:29770/solr DONE. sync succeeded [junit4:junit4] 2> 19438 T316 C27 P29770 REQ /get {sync=3Dhttp://127.0.0.= 1:29763/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt=3D= javabin&version=3D2} status=3D0 QTime=3D13=20 [junit4:junit4] 2> 19439 T301 C25 P29766 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:29766/solr DONE. sync succeeded [junit4:junit4] 2> 19439 T292 oasc.SyncStrategy.syncToMe http://127.0.0.1= :29763/solr/collection1/: sync completed with http://127.0.0.1:29770/solr/= collection1/ [junit4:junit4] 2> 19439 T301 C25 P29766 REQ /get {sync=3Dhttp://127.0.0.= 1:29763/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt=3D= javabin&version=3D2} status=3D0 QTime=3D15=20 [junit4:junit4] 2> 19440 T292 oasc.SyncStrategy.syncToMe http://127.0.0.1= :29763/solr/collection1/: sync completed with http://127.0.0.1:29766/solr/= collection1/ [junit4:junit4] 2> 19441 T260 oasc.AbstractFullDistribZkTestBase.waitForT= hingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt [junit4:junit4] 2> 19441 T260 oasc.AbstractDistribZkTestBase.waitForRecov= eriesToFinish Wait for recoveries to finish - collection: collection1 failO= nTimeout:true timeout (sec):15 [junit4:junit4] 2> 19442 T260 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 19445 T272 C29 P29760 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19447 T272 C29 P29760 oass.SolrIndexSearcher. Op= ening Searcher@74c4ff15 main [junit4:junit4] 2> 19448 T272 C29 P29760 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 19449 T283 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@74c4ff15 main{StandardDirectoryReader(se= gments_2:3:nrt _0(5.0):C4)} [junit4:junit4] 2> 19450 T272 C29 P29760 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 5 [junit4:junit4] 2> 19453 T288 C26 P29763 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19455 T288 C26 P29763 oass.SolrIndexSearcher. Op= ening Searcher@669ca370 main [junit4:junit4] 2> 19455 T288 C26 P29763 oasu.DirectUpdateHandler2.commit= end_commit_flush [junit4:junit4] 2> 19457 T295 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@669ca370 main{StandardDirectoryReader(se= gments_2:3:nrt _0(5.0):C4)} [junit4:junit4] 2> 19461 T288 C26 P29763 oasu.SolrCmdDistributor.distribC= ommit Distrib commit to:[StdNode: http://127.0.0.1:29766/solr/collection1/,= StdNode: http://127.0.0.1:29770/solr/collection1/, StdNode: http://127.0.0= .1:29775/solr/collection1/] [junit4:junit4] 2> 19464 T312 C27 P29770 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19464 T298 C25 P29766 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19464 T326 C28 P29775 oasu.DirectUpdateHandler2.commit= start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,= waitSearcher=3Dtrue,expungeDele [...truncated too long message...] ndler.getStatistics(ReplicationHandler.java:525) [junit4:junit4] 2> =09at org.apache.solr.core.RequestHandlers$LazyRequest= HandlerWrapper.getStatistics(RequestHandlers.java:310) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap$SolrDynamic= MBean.getMBeanInfo(JmxMonitoredMap.java:231) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getClassName(DefaultMBeanServerInterceptor.java:1824) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1661) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1833) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getAttribute(DefaultMBeanServerInterceptor.java:680) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.getAttrib= ute(JmxMBeanServer.java:672) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.unregister(= JmxMonitoredMap.java:174) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.clear(JmxMo= nitoredMap.java:116) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.close(SolrCore.jav= a:864) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .runLeaderProcess(ElectionContext.java:213) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeader= Process(LeaderElector.java:158) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLe= ader(LeaderElector.java:102) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$000(L= eaderElector.java:56) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector$1.process(Le= aderElector.java:131) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.proc= essEvent(ClientCnxn.java:526) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(= ClientCnxn.java:502) [junit4:junit4] 2>=20 [junit4:junit4] 2> 307514 T2598 oasc.JmxMonitoredMap$SolrDynamicMBean.get= MBeanInfo WARNING Could not getStatistics on info bean Lazy[solr.Replicatio= nHandler] java.lang.IllegalArgumentException: /usr/home/hudson/hudson-slave= /workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.a= pache.solr.cloud.BasicDistributedZk2Test-1348353278465/jetty4/index does no= t exist [junit4:junit4] 2> =09at org.apache.commons.io.FileUtils.sizeOfDirectory(= FileUtils.java:2074) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getIn= dexSize(ReplicationHandler.java:477) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getSt= atistics(ReplicationHandler.java:525) [junit4:junit4] 2> =09at org.apache.solr.core.RequestHandlers$LazyRequest= HandlerWrapper.getStatistics(RequestHandlers.java:310) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap$SolrDynamic= MBean.getMBeanInfo(JmxMonitoredMap.java:231) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getClassName(DefaultMBeanServerInterceptor.java:1824) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1661) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1833) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:436) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.unregisterMBean(DefaultMBeanServerInterceptor.java:421) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.unregiste= rMBean(JmxMBeanServer.java:540) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.unregister(= JmxMonitoredMap.java:175) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.clear(JmxMo= nitoredMap.java:116) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.close(SolrCore.jav= a:864) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .runLeaderProcess(ElectionContext.java:213) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeader= Process(LeaderElector.java:158) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLe= ader(LeaderElector.java:102) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$000(L= eaderElector.java:56) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector$1.process(Le= aderElector.java:131) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.proc= essEvent(ClientCnxn.java:526) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(= ClientCnxn.java:502) [junit4:junit4] 2>=20 [junit4:junit4] 2> 307519 T2598 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> 307522 T2598 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D5,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D35,cumulative_delete= sById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> 307522 T2598 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> 307524 T2598 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> 307525 T2598 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> 307525 T2598 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 307525 T2598 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> 307525 T2598 oascc.ZkStateReader$2.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 307525 T2598 oascc.ConnectionManager.process Watcher o= rg.apache.solr.common.cloud.ConnectionManager@5dd1f624 name:ZooKeeperConnec= tion Watcher:127.0.0.1:58258/solr got event WatchedEvent state:Disconnected= type:None path:null path:null type:None [junit4:junit4] 2> 307526 T2598 oascc.ConnectionManager.process Client->Z= ooKeeper status change trigger but we are already closed [junit4:junit4] 2> 307526 T2598 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> NOTE: test params are: codec=3DSimpleText, sim=3DDefau= ltSimilarity, locale=3Dit, timezone=3DAmerica/Cordoba [junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. = 1.6.0_32 (64-bit)/cpus=3D16,threads=3D2,free=3D141107040,total=3D222363648 [junit4:junit4] 2> NOTE: All tests run in this JVM: [TestStressRecovery, = TestPropInject, DistributedQueryElevationComponentTest, TestQuerySenderList= ener, TimeZoneUtilsTest, LegacyHTMLStripCharFilterTest, PingRequestHandlerT= est, FileUtilsTest, StatsComponentTest, UpdateParamsTest, ReturnFieldsTest,= TestDistributedSearch, TestOmitPositions, TestRemoteStreaming, SyncSliceTe= st, TestReplicationHandler, TestCSVResponseWriter, TestUpdate, TestSolrXMLS= erializer, PathHierarchyTokenizerFactoryTest, TestCSVLoader, TestDistribute= dGrouping, TermVectorComponentTest, TestSolrCoreProperties, BasicFunctional= ityTest, DOMUtilTest, OverseerTest, ScriptEngineTest, XmlUpdateRequestHandl= erTest, TestBM25SimilarityFactory, TestReload, TestSort, MultiTermTest, Tes= tArbitraryIndexDir, LukeRequestHandlerTest, LeaderElectionTest, TermVectorC= omponentDistributedTest, TestQueryUtils, FieldMutatingUpdateProcessorTest, = SignatureUpdateProcessorFactoryTest, SolrCoreTest, DocumentBuilderTest, ZkN= odePropsTest, SuggesterTSTTest, HighlighterTest, TestSolrDeletionPolicy1, R= AMDirectoryFactoryTest, ClusterStateUpdateTest, RecoveryZkTest, TestHashPar= titioner, ConvertedLegacyTest, TestFunctionQuery, DebugComponentTest, ShowF= ileRequestHandlerTest, TestCodecSupport, DisMaxRequestHandlerTest, LeaderEl= ectionIntegrationTest, ZkControllerTest, TestExtendedDismaxParser, BasicZkT= est, DistributedTermsComponentTest, TestRecovery, DirectSolrSpellCheckerTes= t, PeerSyncTest, SortByFunctionTest, TestBinaryResponseWriter, CoreAdminHan= dlerTest, SolrRequestParserTest, CacheHeaderTest, PolyFieldTest, MBeansHand= lerTest, QueryParsingTest, TermsComponentTest, XsltUpdateRequestHandlerTest= , StandardRequestHandlerTest, JsonLoaderTest, DocumentAnalysisRequestHandle= rTest, IndexSchemaRuntimeFieldTest, FastVectorHighlighterTest, RequestHandl= ersTest, RequiredFieldsTest, SolrCmdDistributorTest, TestFoldingMultitermQu= ery, SolrCoreCheckLockOnStartupTest, TestWriterPerf, AlternateDirectoryTest= , HighlighterConfigTest, TestPropInjectDefaults, TestMergePolicyConfig, Tes= tCollationField, TestQuerySenderNoQuery, OutputWriterTest, SpellPossibility= IteratorTest, TestPhraseSuggestions, TestDefaultSimilarityFactory, TestFast= LRUCache, URLClassifyProcessorTest, SpellingQueryConverterTest, TestLRUCach= e, UUIDFieldTest, PreAnalyzedFieldTest, TestDocumentBuilder, BasicDistribut= edZk2Test] [junit4:junit4] Completed on J1 in 307.90s, 1 test, 1 failure <<< FAILURES! [...truncated 11 lines...] BUILD FAILED /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/build= .xml:32: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/= build.xml:179: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucen= e/module-build.xml:63: The following error occurred while executing this li= ne: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucen= e/common-build.xml:1141: The following error occurred while executing this = line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucen= e/common-build.xml:814: There were test failures: 230 suites, 950 tests, 1 = error, 1 failure, 21 ignored (3 assumptions) Total time: 35 minutes 37 seconds Build step 'Invoke Ant' marked build as failure Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_150_311724086.1348353592562 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_150_311724086.1348353592562--