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 075F110FFB for ; Tue, 9 Apr 2013 08:39:55 +0000 (UTC) Received: (qmail 68249 invoked by uid 500); 9 Apr 2013 08:39:53 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 68105 invoked by uid 500); 9 Apr 2013 08:39:52 -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 68058 invoked by uid 99); 9 Apr 2013 08:39:50 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 09 Apr 2013 08:39:50 +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; Tue, 09 Apr 2013 08:39:28 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id 1E1F2C0071 for ; Tue, 9 Apr 2013 08:39:06 +0000 (UTC) Date: Tue, 9 Apr 2013 08:38:45 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <381922973.969.1365496746121.JavaMail.hudson@aegis> In-Reply-To: <1944172544.902.1365477885095.JavaMail.hudson@aegis> References: <1944172544.902.1365477885095.JavaMail.hudson@aegis> Subject: [JENKINS] Lucene-Solr-Tests-4.x-java7 - Build # 1130 - Still Failing MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_968_1751208919.1365496725736" X-Jenkins-Job: Lucene-Solr-Tests-4.x-java7 X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_968_1751208919.1365496725736 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-java7/1130/ 3 tests failed. REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDist= ribSearch Error Message: There are still nodes recoverying - waited for 230 seconds Stack Trace: java.lang.AssertionError: There are still nodes recoverying - waited for 23= 0 seconds =09at __randomizedtesting.SeedInfo.seed([582D368C62EB2001:D9CBB89415B4403D]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:173) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:131) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:126) =09at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollections= API(CollectionsAPIDistributedZkTest.java:483) =09at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(Collecti= onsAPIDistributedZkTest.java:146) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:806) =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:601) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at java.lang.Thread.run(Thread.java:722) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDist= ributedZkTest Error Message: 2 threads leaked from SUITE scope at org.apache.solr.cloud.CollectionsAPIDi= stributedZkTest: 1) Thread[id=3D4109, name=3DrecoveryCmdExecutor-1612-t= hread-2, state=3DRUNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] = at java.net.PlainSocketImpl.socketConnect(Native Method) at j= ava.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)= at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlain= SocketImpl.java:200) at java.net.AbstractPlainSocketImpl.connect(Ab= stractPlainSocketImpl.java:182) at java.net.SocksSocketImpl.connect= (SocksSocketImpl.java:391) at java.net.Socket.connect(Socket.java:5= 79) at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket= (PlainSocketFactory.java:127) at org.apache.http.impl.conn.DefaultC= lientConnectionOperator.openConnection(DefaultClientConnectionOperator.java= :180) at org.apache.http.impl.conn.ManagedClientConnectionImpl.open= (ManagedClientConnectionImpl.java:294) at org.apache.http.impl.clie= nt.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645) = at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultReq= uestDirector.java:480) at org.apache.http.impl.client.AbstractHttpC= lient.execute(AbstractHttpClient.java:906) at org.apache.http.impl.= client.AbstractHttpClient.execute(AbstractHttpClient.java:805) at o= rg.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.ja= va:784) at org.apache.solr.client.solrj.impl.HttpSolrServer.request= (HttpSolrServer.java:353) at org.apache.solr.client.solrj.impl.Http= SolrServer.request(HttpSolrServer.java:181) at org.apache.solr.clou= d.SyncStrategy$1.run(SyncStrategy.java:298) at java.util.concurrent= .ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java= .util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)= at java.lang.Thread.run(Thread.java:722) 2) Thread[id=3D4107, n= ame=3DrecoveryCmdExecutor-1612-thread-1, state=3DRUNNABLE, group=3DTGRP-Col= lectionsAPIDistributedZkTest] at java.net.PlainSocketImpl.socketCon= nect(Native Method) at java.net.AbstractPlainSocketImpl.doConnect(A= bstractPlainSocketImpl.java:339) at java.net.AbstractPlainSocketImp= l.connectToAddress(AbstractPlainSocketImpl.java:200) at java.net.Ab= stractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) at = java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391) at java.= net.Socket.connect(Socket.java:579) at org.apache.http.conn.scheme.= PlainSocketFactory.connectSocket(PlainSocketFactory.java:127) at or= g.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(Defa= ultClientConnectionOperator.java:180) at org.apache.http.impl.conn.= ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294) = at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(Defaul= tRequestDirector.java:645) at org.apache.http.impl.client.DefaultRe= questDirector.execute(DefaultRequestDirector.java:480) at org.apach= e.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906) = at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:805) at org.apache.http.impl.client.AbstractHttpClie= nt.execute(AbstractHttpClient.java:784) at org.apache.solr.client.s= olrj.impl.HttpSolrServer.request(HttpSolrServer.java:353) at org.ap= ache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)= at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:298)= at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExe= cutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.jav= a:722) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from S= UITE scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest:=20 1) Thread[id=3D4109, name=3DrecoveryCmdExecutor-1612-thread-2, state=3DR= UNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketIm= pl.java:339) at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainS= ocketImpl.java:200) at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl= .java:182) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391) at java.net.Socket.connect(Socket.java:579) at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(Pla= inSocketFactory.java:127) at org.apache.http.impl.conn.DefaultClientConnectionOperator.openCo= nnection(DefaultClientConnectionOperator.java:180) at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(Manag= edClientConnectionImpl.java:294) at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(De= faultRequestDirector.java:645) at org.apache.http.impl.client.DefaultRequestDirector.execute(Defau= ltRequestDirector.java:480) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:906) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:805) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:784) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSol= rServer.java:353) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSol= rServer.java:181) at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:298) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:722) 2) Thread[id=3D4107, name=3DrecoveryCmdExecutor-1612-thread-1, state=3DR= UNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketIm= pl.java:339) at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainS= ocketImpl.java:200) at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl= .java:182) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391) at java.net.Socket.connect(Socket.java:579) at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(Pla= inSocketFactory.java:127) at org.apache.http.impl.conn.DefaultClientConnectionOperator.openCo= nnection(DefaultClientConnectionOperator.java:180) at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(Manag= edClientConnectionImpl.java:294) at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(De= faultRequestDirector.java:645) at org.apache.http.impl.client.DefaultRequestDirector.execute(Defau= ltRequestDirector.java:480) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:906) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:805) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:784) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSol= rServer.java:353) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSol= rServer.java:181) at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:298) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:722) =09at __randomizedtesting.SeedInfo.seed([582D368C62EB2001]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDist= ributedZkTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id= =3D4109, name=3DrecoveryCmdExecutor-1612-thread-2, state=3DRUNNABLE, group= =3DTGRP-CollectionsAPIDistributedZkTest] at java.net.PlainSocketImp= l.socketConnect(Native Method) at java.net.AbstractPlainSocketImpl.= doConnect(AbstractPlainSocketImpl.java:339) at java.net.AbstractPla= inSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) at = java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) = at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391) = at java.net.Socket.connect(Socket.java:579) at org.apache.http.c= onn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127) = at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConn= ection(DefaultClientConnectionOperator.java:180) at org.apache.http= .impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.jav= a:294) at org.apache.http.impl.client.DefaultRequestDirector.tryCon= nect(DefaultRequestDirector.java:645) at org.apache.http.impl.clien= t.DefaultRequestDirector.execute(DefaultRequestDirector.java:480) a= t org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient= .java:906) at org.apache.http.impl.client.AbstractHttpClient.execut= e(AbstractHttpClient.java:805) at org.apache.http.impl.client.Abstr= actHttpClient.execute(AbstractHttpClient.java:784) at org.apache.so= lr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:353) = at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServe= r.java:181) at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrateg= y.java:298) at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecu= tor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run= (Thread.java:722) 2) Thread[id=3D4107, name=3DrecoveryCmdExecutor-1612-t= hread-1, state=3DRUNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] = at java.net.PlainSocketImpl.socketConnect(Native Method) at j= ava.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)= at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlain= SocketImpl.java:200) at java.net.AbstractPlainSocketImpl.connect(Ab= stractPlainSocketImpl.java:182) at java.net.SocksSocketImpl.connect= (SocksSocketImpl.java:391) at java.net.Socket.connect(Socket.java:5= 79) at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket= (PlainSocketFactory.java:127) at org.apache.http.impl.conn.DefaultC= lientConnectionOperator.openConnection(DefaultClientConnectionOperator.java= :180) at org.apache.http.impl.conn.ManagedClientConnectionImpl.open= (ManagedClientConnectionImpl.java:294) at org.apache.http.impl.clie= nt.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645) = at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultReq= uestDirector.java:480) at org.apache.http.impl.client.AbstractHttpC= lient.execute(AbstractHttpClient.java:906) at org.apache.http.impl.= client.AbstractHttpClient.execute(AbstractHttpClient.java:805) at o= rg.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.ja= va:784) at org.apache.solr.client.solrj.impl.HttpSolrServer.request= (HttpSolrServer.java:353) at org.apache.solr.client.solrj.impl.Http= SolrServer.request(HttpSolrServer.java:181) at org.apache.solr.clou= d.SyncStrategy$1.run(SyncStrategy.java:298) at java.util.concurrent= .ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java= .util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)= at java.lang.Thread.run(Thread.java:722) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie = threads that couldn't be terminated: 1) Thread[id=3D4109, name=3DrecoveryCmdExecutor-1612-thread-2, state=3DR= UNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketIm= pl.java:339) at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainS= ocketImpl.java:200) at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl= .java:182) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391) at java.net.Socket.connect(Socket.java:579) at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(Pla= inSocketFactory.java:127) at org.apache.http.impl.conn.DefaultClientConnectionOperator.openCo= nnection(DefaultClientConnectionOperator.java:180) at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(Manag= edClientConnectionImpl.java:294) at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(De= faultRequestDirector.java:645) at org.apache.http.impl.client.DefaultRequestDirector.execute(Defau= ltRequestDirector.java:480) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:906) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:805) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:784) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSol= rServer.java:353) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSol= rServer.java:181) at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:298) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:722) 2) Thread[id=3D4107, name=3DrecoveryCmdExecutor-1612-thread-1, state=3DR= UNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketIm= pl.java:339) at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainS= ocketImpl.java:200) at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl= .java:182) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391) at java.net.Socket.connect(Socket.java:579) at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(Pla= inSocketFactory.java:127) at org.apache.http.impl.conn.DefaultClientConnectionOperator.openCo= nnection(DefaultClientConnectionOperator.java:180) at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(Manag= edClientConnectionImpl.java:294) at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(De= faultRequestDirector.java:645) at org.apache.http.impl.client.DefaultRequestDirector.execute(Defau= ltRequestDirector.java:480) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:906) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:805) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractH= ttpClient.java:784) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSol= rServer.java:353) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSol= rServer.java:181) at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:298) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:722) =09at __randomizedtesting.SeedInfo.seed([582D368C62EB2001]:0) Build Log: [...truncated 9344 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTes= t [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.409; org.apache.solr.BaseD= istributedSearchTestCase; Setting hostContext system property: /dwl/rf [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.416; org.apache.solr.SolrT= estCaseJ4; ###Starting testDistribSearch [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.417; org.apache.solr.cloud= .ZkTestServer; STARTING ZK TEST SERVER [junit4:junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.418; org.apache.solr.cloud= .ZkTestServer$ZKServerMain; Starting server [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.519; org.apache.solr.cloud= .ZkTestServer; start zk server on port:41662 [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.520; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.524; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@7fe1ac3a name:ZooKeeperConnection Watcher:127.0.0.1:41662 got event = WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.525; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.525; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /solr [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.530; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.539; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@4cdc22d7 name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.539; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.540; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/collection1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.544; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/collection1/shards [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.553; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/control_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.556; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/control_collection/shards [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.559; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/sol= rconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.560; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.575; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/sch= ema.xml to /configs/conf1/schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.576; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.683; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/sto= pwords.txt to /configs/conf1/stopwords.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.684; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/stopwords.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.687; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/pro= twords.txt to /configs/conf1/protwords.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.688; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/protwords.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.699; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/cur= rency.xml to /configs/conf1/currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.700; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.703; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/ope= n-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.704; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/open-exchange-rates.json [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.714; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/map= ping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.715; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.723; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/old= _synonyms.txt to /configs/conf1/old_synonyms.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.724; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/old_synonyms.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.727; org.apache.solr.cloud= .AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/syn= onyms.txt to /configs/conf1/synonyms.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.728; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /configs/conf1/synonyms.txt [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.949; org.eclipse.jetty.ser= ver.Server; jetty-8.1.8.v20121106 [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.953; org.eclipse.jetty.ser= ver.AbstractConnector; Started SocketConnector@127.0.0.1:41669 [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.954; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.955; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.956; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: /usr/home/hudson/= hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/tes= t/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1= 365496329738 [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.956; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: /usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-13= 65496329738/solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.957; org.apache.solr.core.= CoreContainer; New CoreContainer 830965657 [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.958; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '/usr/home/hudson/hud= son-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J= 0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1365= 496329738/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:09.958; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-= 1365496329738/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.025; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.026; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.027; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 15000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.028; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.028; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.029; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.029; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.030; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.031; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.031; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D120000&connTimeout=3D1= 5000&retry=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.052; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.072; org.apache.solr.core.= CoreContainer; Zookeeper client=3D127.0.0.1:41662/solr [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.073; org.apache.solr.cloud= .ZkController; zkHost includes chroot [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.074; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.101; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@11b33842 name:ZooKeeperConnection Watcher:127.0.0.1:41662 got event = WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.102; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.107; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D500&maxConnectionsPerHost=3D16&socketTimeout=3D120000&connTimeout=3D150= 00 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.117; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.119; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@6a4652a0 name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.120; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.122; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /overseer/queue [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.132; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /overseer/collection-queue-work [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.136; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /live_nodes [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.143; org.apache.solr.cloud= .ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:416= 69_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.144; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:41669_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.148; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /overseer_elect/election [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.165; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /overseer_elect/leader [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.168; org.apache.solr.cloud= .Overseer; Overseer (id=3D89489167445065731-127.0.0.1:41669_dwl%2Frf-n_0000= 000000) starting [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.182; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /overseer/queue-work [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.186; org.apache.solr.cloud= .OverseerCollectionProcessor; Process current queue of collection creations [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.186; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /clusterstate.json [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.194; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /aliases.json [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.196; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cluster state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.199; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Starting to work on the main queue [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.202; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3D/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.203; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.203; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.213; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config: [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.215; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.217; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@15504377 name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.217; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.218; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cluster state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.220; org.apache.solr.cloud= .ChaosMonkey; monkey: init - expire sessions:true cause connection loss:tru= e [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.434; org.eclipse.jetty.ser= ver.Server; jetty-8.1.8.v20121106 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.437; org.eclipse.jetty.ser= ver.AbstractConnector; Started SocketConnector@127.0.0.1:41673 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.438; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.439; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.439; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: /usr/home/hudson/= hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/tes= t/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1365496= 330221 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.440; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: /usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-13654963= 30221/solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.440; org.apache.solr.core.= CoreContainer; New CoreContainer 1679028893 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.441; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '/usr/home/hudson/hud= son-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J= 0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1365496330= 221/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.442; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136549= 6330221/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.510; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.511; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.512; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 15000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.512; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.513; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.514; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.514; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.515; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.515; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.516; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D120000&connTimeout=3D1= 5000&retry=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.537; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.558; org.apache.solr.core.= CoreContainer; Zookeeper client=3D127.0.0.1:41662/solr [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.559; org.apache.solr.cloud= .ZkController; zkHost includes chroot [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.561; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.564; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@5ff4b80e name:ZooKeeperConnection Watcher:127.0.0.1:41662 got event = WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.566; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.575; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D500&maxConnectionsPerHost=3D16&socketTimeout=3D120000&connTimeout=3D150= 00 [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.586; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.588; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@2c03b5da name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.588; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:10.597; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cluster state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.600; org.apache.solr.cloud= .ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:416= 73_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.602; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:41673_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.605; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (2) [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.605; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (2) [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.605; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (2) [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.620; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3D/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.621; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.621; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.839; org.eclipse.jetty.ser= ver.Server; jetty-8.1.8.v20121106 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.843; org.eclipse.jetty.ser= ver.AbstractConnector; Started SocketConnector@127.0.0.1:41676 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.844; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.844; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.845; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: /usr/home/hudson/= hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/tes= t/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1365496= 331631 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.846; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: /usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-13654963= 31631/solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.846; org.apache.solr.core.= CoreContainer; New CoreContainer 731124362 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.847; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '/usr/home/hudson/hud= son-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J= 0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1365496331= 631/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.848; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-136549= 6331631/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.915; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.916; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.917; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 15000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.918; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.918; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.919; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.919; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.920; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.921; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.921; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D120000&connTimeout=3D1= 5000&retry=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.942; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.962; org.apache.solr.core.= CoreContainer; Zookeeper client=3D127.0.0.1:41662/solr [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.963; org.apache.solr.cloud= .ZkController; zkHost includes chroot [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.964; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.967; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@4b714f06 name:ZooKeeperConnection Watcher:127.0.0.1:41662 got event = WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.968; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.970; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D500&maxConnectionsPerHost=3D16&socketTimeout=3D120000&connTimeout=3D150= 00 [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.981; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.982; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@1fa52d3a name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.983; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:11.995; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cluster state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:12.998; org.apache.solr.cloud= .ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:416= 76_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.001; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:41676_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.039; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (3) [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.039; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (3) [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.039; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (3) [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.039; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (3) [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.046; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3D/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.046; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.047; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.310; org.eclipse.jetty.ser= ver.Server; jetty-8.1.8.v20121106 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.314; org.eclipse.jetty.ser= ver.AbstractConnector; Started SocketConnector@127.0.0.1:41679 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.315; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.315; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.316; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: /usr/home/hudson/= hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/tes= t/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1365496= 333057 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.317; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: /usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-13654963= 33057/solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.317; org.apache.solr.core.= CoreContainer; New CoreContainer 1397971750 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.318; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '/usr/home/hudson/hud= son-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J= 0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1365496333= 057/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.319; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-136549= 6333057/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.386; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.387; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.388; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 15000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.389; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.389; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.390; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.390; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.391; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.391; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.392; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D120000&connTimeout=3D1= 5000&retry=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.412; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.433; org.apache.solr.core.= CoreContainer; Zookeeper client=3D127.0.0.1:41662/solr [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.434; org.apache.solr.cloud= .ZkController; zkHost includes chroot [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.435; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.439; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@6635e29c name:ZooKeeperConnection Watcher:127.0.0.1:41662 got event = WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.440; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.442; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D500&maxConnectionsPerHost=3D16&socketTimeout=3D120000&connTimeout=3D150= 00 [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.452; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.454; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@2b25d87c name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.454; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:13.465; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cluster state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.468; org.apache.solr.cloud= .ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:416= 79_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.471; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:41679_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.473; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (4) [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.474; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (4) [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.474; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (4) [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.474; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (4) [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.473; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (4) [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.489; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3D/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.489; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.490; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.703; org.eclipse.jetty.ser= ver.Server; jetty-8.1.8.v20121106 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.706; org.eclipse.jetty.ser= ver.AbstractConnector; Started SocketConnector@127.0.0.1:41682 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.707; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.708; org.apache.solr.core.= SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx) [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.709; org.apache.solr.core.= SolrResourceLoader; using system property solr.solr.home: /usr/home/hudson/= hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/tes= t/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1365496= 334500 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.709; org.apache.solr.core.= CoreContainer$Initializer; looking for solr config file: /usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-13654963= 34500/solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.710; org.apache.solr.core.= CoreContainer; New CoreContainer 1106494956 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.710; org.apache.solr.core.= CoreContainer; Loading CoreContainer using Solr Home: '/usr/home/hudson/hud= son-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J= 0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1365496334= 500/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.711; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136549= 6334500/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.778; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.779; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting urlScheme to: http:// [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.780; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting connTimeout to: 15000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.780; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.781; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting corePoolSize to: 0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.781; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 214748364= 7 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.782; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.783; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.783; org.apache.solr.handl= er.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.784; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= sPerHost=3D20&maxConnections=3D10000&socketTimeout=3D120000&connTimeout=3D1= 5000&retry=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.804; org.apache.solr.core.= CoreContainer; Registering Log Listener [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.824; org.apache.solr.core.= CoreContainer; Zookeeper client=3D127.0.0.1:41662/solr [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.825; org.apache.solr.cloud= .ZkController; zkHost includes chroot [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.826; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.829; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@25b748f4 name:ZooKeeperConnection Watcher:127.0.0.1:41662 got event = WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.829; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.831; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D500&maxConnectionsPerHost=3D16&socketTimeout=3D120000&connTimeout=3D150= 00 [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.842; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.844; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@3f930344 name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.844; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:14.856; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cluster state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.859; org.apache.solr.cloud= .ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:416= 82_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.862; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:41682_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.865; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.865; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.865; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.865; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.865; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.865; org.apache.solr.commo= n.cloud.ZkStateReader$3; Updating live nodes... (5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.882; org.apache.solr.servl= et.SolrDispatchFilter; user.dir=3D/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.883; org.apache.solr.servl= et.SolrDispatchFilter; SolrDispatchFilter.init() done [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.883; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.894; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.905; org.apache.solr.handl= er.admin.CollectionsHandler; Creating Collection : numShards=3D2&name=3Dnod= es_used_collection&replicationFactor=3D2&action=3DCREATE&wt=3Djavabin&versi= on=3D2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.908; org.apache.solr.cloud= .DistributedQueue$LatchChildWatcher; Watcher fired on path: /overseer/colle= ction-queue-work state: SyncConnected type NodeChildrenChanged [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.915; org.apache.solr.cloud= .OverseerCollectionProcessor; Overseer Collection Processor: Get the messag= e id:/overseer/collection-queue-work/qn-0000000000 message:{ [junit4:junit4] 1> "operation":"createcollection", [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "maxShardsPerNode":null, [junit4:junit4] 1> "createNodeSet":null, [junit4:junit4] 1> "name":"nodes_used_collection", [junit4:junit4] 1> "replicationFactor":"2"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.915; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard nodes_used_collection_shard1_r= eplica1 as part of slice shard1 of collection nodes_used_collection on 127.= 0.0.1:41682_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.916; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard nodes_used_collection_shard1_r= eplica2 as part of slice shard1 of collection nodes_used_collection on 127.= 0.0.1:41676_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.917; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard nodes_used_collection_shard2_r= eplica1 as part of slice shard2 of collection nodes_used_collection on 127.= 0.0.1:41669_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.917; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard nodes_used_collection_shard2_r= eplica2 as part of slice shard2 of collection nodes_used_collection on 127.= 0.0.1:41673_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.919; org.apache.solr.core.= CoreContainer; Creating SolrCore 'nodes_used_collection_shard1_replica2' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty2-1365496331631/nodes_used_collection_shard1_repli= ca2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.919; org.apache.solr.core.= CoreContainer; Creating SolrCore 'nodes_used_collection_shard2_replica1' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-controljetty-1365496329738/nodes_used_collection_shard2= _replica1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.919; org.apache.solr.core.= CoreContainer; Creating SolrCore 'nodes_used_collection_shard1_replica1' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty4-1365496334500/nodes_used_collection_shard1_repli= ca1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.920; org.apache.solr.cloud= .ZkController; Check for collection zkNode:nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.919; org.apache.solr.core.= CoreContainer; Creating SolrCore 'nodes_used_collection_shard2_replica2' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty1-1365496330221/nodes_used_collection_shard2_repli= ca2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.921; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.919; org.apache.solr.cloud= .ZkController; Check for collection zkNode:nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.922; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.922; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.921; org.apache.solr.cloud= .ZkController; Check for collection zkNode:nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.920; org.apache.solr.cloud= .ZkController; Check for collection zkNode:nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.923; org.apache.solr.cloud= .ZkController; Only one config set found in zk - using it:conf1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.924; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.923; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.925; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.924; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.924; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.927; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.927; org.apache.solr.cloud= .ZkController; Only one config set found in zk - using it:conf1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.927; org.apache.solr.cloud= .ZkController; Only one config set found in zk - using it:conf1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.928; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.929; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.930; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.930; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/nodes_used_collecti= on [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.931; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/nodes_used_collecti= on [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.941; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/nodes_used_collecti= on [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.942; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-= 1365496329738/nodes_used_collection_shard2_replica1/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.943; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/nodes_used_collecti= on [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.944; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136549= 6334500/nodes_used_collection_shard1_replica1/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.944; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-136549= 6331631/nodes_used_collection_shard1_replica2/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:15.945; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136549= 6330221/nodes_used_collection_shard2_replica2/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.000; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.008; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.011; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.016; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.128; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.137; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.138; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.142; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.207; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.213; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.238; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.239; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.243; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.249; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.249; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:16.249; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.035; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Initialized with rates=3Dopen-exchange-rate= s.json, refreshInterval=3D1440. [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.045; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Initialized with rates=3Dopen-exchange-rate= s.json, refreshInterval=3D1440. [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.048; org.apache.solr.schem= a.IndexSchema; default search field in schema is text [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.053; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.054; org.apache.solr.schem= a.IndexSchema; default search field in schema is text [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.059; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.067; org.apache.solr.schem= a.FileExchangeRateProvider; Reloading exchange rates from file currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.072; org.apache.solr.schem= a.FileExchangeRateProvider; Reloading exchange rates from file currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.072; org.apache.solr.schem= a.FileExchangeRateProvider; Reloading exchange rates from file currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.078; org.apache.solr.schem= a.FileExchangeRateProvider; Reloading exchange rates from file currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.078; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange= -rates.json [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.079; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOT= E [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.080; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.080; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange= -rates.json [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.081; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOT= E [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.082; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.082; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.083; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange= -rates.json [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.083; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard2_replica1] Opening new SolrCore at /= usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/bui= ld/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTes= t-controljetty-1365496329738/nodes_used_collection_shard2_replica1/, dataDi= r=3D/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/sol= r/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributed= ZkTest-controljetty-1365496329738/nodes_used_collection_shard2_replica1/dat= a/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.084; org.apache.solr.core.= JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Serve= r: com.sun.jmx.mbeanserver.JmxMBeanServer@9981537 [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.084; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOT= E [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.085; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.087; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.088; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange= -rates.json [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.089; org.apache.solr.core.= CachingDirectoryFactory; return new directory for /usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./o= rg.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-136549632= 9738/nodes_used_collection_shard2_replica1/data [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.089; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOT= E [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.090; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.089; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D/usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-13= 65496329738/nodes_used_collection_shard2_replica1/data/index/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.090; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.091; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard2_replica1] Solr index directory '/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-= controljetty-1365496329738/nodes_used_collection_shard2_replica1/data/index= ' doesn't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.091; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard1_replica2] Opening new SolrCore at /= usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/bui= ld/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTes= t-jetty2-1365496331631/nodes_used_collection_shard1_replica2/, dataDir=3D/u= sr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest= -jetty2-1365496331631/nodes_used_collection_shard1_replica2/data/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.093; org.apache.solr.core.= CachingDirectoryFactory; return new directory for /usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./o= rg.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-136549632= 9738/nodes_used_collection_shard2_replica1/data/index [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.093; org.apache.solr.core.= JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Serve= r: com.sun.jmx.mbeanserver.JmxMBeanServer@9981537 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.095; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.096; org.apache.solr.core.= CachingDirectoryFactory; return new directory for /usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./o= rg.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1365496331631/n= odes_used_collection_shard1_replica2/data [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.096; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D/usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-13654963= 31631/nodes_used_collection_shard1_replica2/data/index/ [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.097; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard1_replica2] Solr index directory '/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-= jetty2-1365496331631/nodes_used_collection_shard1_replica2/data/index' does= n't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.097; org.apache.solr.core.= CachingDirectoryFactory; return new directory for /usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./o= rg.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1365496331631/n= odes_used_collection_shard1_replica2/data/index [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.100; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@3a6f57de lockFactory=3Dorg.apache.lucene.store.NativeFSLockFa= ctory@1343ff2d),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.101; org.apache.solr.core.= SolrDeletionPolicy; newest commit =3D 1[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.102; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@6972556c lockFactory=3Dorg.apache.lucene.store.NativeFSLockFa= ctory@13300878),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.103; org.apache.solr.core.= SolrDeletionPolicy; newest commit =3D 1[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.105; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "dedupe" [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.106; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "stored_sig" [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.106; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "dedupe" [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.107; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "stored_sig" [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.107; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit= " [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.108; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit= " [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.109; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.109; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.110; org.apache.solr.core.= RequestHandlers; created /get: solr.RealTimeGetHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.110; org.apache.solr.core.= RequestHandlers; created /get: solr.RealTimeGetHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.111; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.110; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.111; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.112; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.112; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.113; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.113; org.apache.solr.core.= RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandle= rs [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.114; org.apache.solr.core.= RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandle= rs [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.130; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.130; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.134; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@6773be35 main [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.135; org.apache.solr.updat= e.UpdateHandler; Clearing tlog files, tlogDir=3D/usr/home/hudson/hudson-sla= ve/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org= .apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1365496331631/nod= es_used_collection_shard1_replica2/data/tlog [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.136; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@3a4a9743 main [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.136; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.137; org.apache.solr.updat= e.UpdateHandler; Clearing tlog files, tlogDir=3D/usr/home/hudson/hudson-sla= ve/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org= .apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-13654963297= 38/nodes_used_collection_shard2_replica1/data/tlog [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.137; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.139; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.139; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.142; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard1_replica2] Registered new searcher S= earcher@6773be35 main{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.144; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard2_replica1] Registered new searcher S= earcher@3a4a9743 main{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.144; org.apache.solr.cloud= .ZkController; publishing core=3Dnodes_used_collection_shard1_replica2 stat= e=3Ddown [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.147; org.apache.solr.cloud= .ZkController; publishing core=3Dnodes_used_collection_shard2_replica1 stat= e=3Ddown [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.246; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Initialized with rates=3Dopen-exchange-rate= s.json, refreshInterval=3D1440. [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.259; org.apache.solr.schem= a.IndexSchema; default search field in schema is text [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.264; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.269; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Initialized with rates=3Dopen-exchange-rate= s.json, refreshInterval=3D1440. [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.277; org.apache.solr.schem= a.FileExchangeRateProvider; Reloading exchange rates from file currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.278; org.apache.solr.schem= a.IndexSchema; default search field in schema is text [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.283; org.apache.solr.schem= a.IndexSchema; unique key field: id [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.297; org.apache.solr.schem= a.FileExchangeRateProvider; Reloading exchange rates from file currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.298; org.apache.solr.schem= a.FileExchangeRateProvider; Reloading exchange rates from file currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.303; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange= -rates.json [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.304; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOT= E [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.305; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.305; org.apache.solr.schem= a.FileExchangeRateProvider; Reloading exchange rates from file currency.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.305; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange= -rates.json [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.307; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOT= E [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.308; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.308; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.309; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard1_replica1] Opening new SolrCore at /= usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/bui= ld/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTes= t-jetty4-1365496334500/nodes_used_collection_shard1_replica1/, dataDir=3D/u= sr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest= -jetty4-1365496334500/nodes_used_collection_shard1_replica1/data/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.309; org.apache.solr.core.= JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Serve= r: com.sun.jmx.mbeanserver.JmxMBeanServer@9981537 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.310; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.311; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange= -rates.json [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.311; org.apache.solr.core.= CachingDirectoryFactory; return new directory for /usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./o= rg.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1365496334500/n= odes_used_collection_shard1_replica1/data [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.312; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D/usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-13654963= 34500/nodes_used_collection_shard1_replica1/data/index/ [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.312; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOT= E [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.313; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.312; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard1_replica1] Solr index directory '/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-= jetty4-1365496334500/nodes_used_collection_shard1_replica1/data/index' does= n't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.313; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange= -rates.json [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.314; org.apache.solr.core.= CachingDirectoryFactory; return new directory for /usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./o= rg.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1365496334500/n= odes_used_collection_shard1_replica1/data/index [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.315; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOT= E [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.316; org.apache.solr.schem= a.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.316; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.317; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard2_replica2] Opening new SolrCore at /= usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/bui= ld/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTes= t-jetty1-1365496330221/nodes_used_collection_shard2_replica2/, dataDir=3D/u= sr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest= -jetty1-1365496330221/nodes_used_collection_shard2_replica2/data/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.317; org.apache.solr.core.= JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Serve= r: com.sun.jmx.mbeanserver.JmxMBeanServer@9981537 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.318; org.apache.solr.core.= SolrCore; org.apache.solr.core.MockDirectoryFactory [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.319; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@35fbe77d lockFactory=3Dorg.apache.lucene.store.NativeFSLockFa= ctory@534999e),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.319; org.apache.solr.core.= CachingDirectoryFactory; return new directory for /usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./o= rg.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1365496330221/n= odes_used_collection_shard2_replica2/data [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.319; org.apache.solr.core.= SolrDeletionPolicy; newest commit =3D 1[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.320; org.apache.solr.core.= SolrCore; New index directory detected: old=3Dnull new=3D/usr/home/hudson/h= udson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-13654963= 30221/nodes_used_collection_shard2_replica2/data/index/ [junit4:junit4] 1> WARN - 2013-04-09 08:32:17.321; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard2_replica2] Solr index directory '/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-= jetty1-1365496330221/nodes_used_collection_shard2_replica2/data/index' does= n't exist. Creating new index... [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.322; org.apache.solr.core.= CachingDirectoryFactory; return new directory for /usr/home/hudson/hudson-s= lave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./o= rg.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1365496330221/n= odes_used_collection_shard2_replica2/data/index [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.323; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "dedupe" [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.324; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "stored_sig" [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.325; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit= " [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.326; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.326; org.apache.solr.core.= RequestHandlers; created /get: solr.RealTimeGetHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.327; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.327; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@434e3ee8 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFa= ctory@4a4a7697),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.327; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.328; org.apache.solr.core.= SolrDeletionPolicy; newest commit =3D 1[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.329; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.329; org.apache.solr.core.= RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandle= rs [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.331; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "dedupe" [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.332; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "stored_sig" [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.333; org.apache.solr.updat= e.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcess= orFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit= " [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.334; org.apache.solr.core.= RequestHandlers; created standard: solr.StandardRequestHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.335; org.apache.solr.core.= RequestHandlers; created /get: solr.RealTimeGetHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.335; org.apache.solr.core.= RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.336; org.apache.solr.core.= RequestHandlers; created /replication: solr.ReplicationHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.336; org.apache.solr.core.= RequestHandlers; created /update: solr.UpdateRequestHandler [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.337; org.apache.solr.core.= RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandle= rs [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.348; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.349; org.apache.solr.handl= er.loader.XMLLoader; xsltCacheLifetimeSeconds=3D60 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.358; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@58e9b005 main [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.358; org.apache.solr.searc= h.SolrIndexSearcher; Opening Searcher@48e7c37a main [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.359; org.apache.solr.updat= e.UpdateHandler; Clearing tlog files, tlogDir=3D/usr/home/hudson/hudson-sla= ve/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org= .apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1365496334500/nod= es_used_collection_shard1_replica1/data/tlog [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.360; org.apache.solr.updat= e.UpdateHandler; Clearing tlog files, tlogDir=3D/usr/home/hudson/hudson-sla= ve/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org= .apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1365496330221/nod= es_used_collection_shard2_replica2/data/tlog [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.362; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.363; org.apache.solr.updat= e.CommitTracker; Hard AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.363; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.364; org.apache.solr.updat= e.CommitTracker; Soft AutoCommit: disabled [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.370; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard1_replica1] Registered new searcher S= earcher@58e9b005 main{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.372; org.apache.solr.cloud= .ZkController; publishing core=3Dnodes_used_collection_shard2_replica2 stat= e=3Ddown [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.373; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard2_replica2] Registered new searcher S= earcher@48e7c37a main{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.373; org.apache.solr.cloud= .ZkController; publishing core=3Dnodes_used_collection_shard1_replica1 stat= e=3Ddown [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.710; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cloud state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.711; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Update state numShards=3D2 message=3D{ [junit4:junit4] 1> "operation":"state", [junit4:junit4] 1> "core_node_name":null, [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "shard":"shard1", [junit4:junit4] 1> "roles":null, [junit4:junit4] 1> "state":"down", [junit4:junit4] 1> "shard_state":"active", [junit4:junit4] 1> "core":"nodes_used_collection_shard1_replica2", [junit4:junit4] 1> "collection":"nodes_used_collection", [junit4:junit4] 1> "shard_range":null, [junit4:junit4] 1> "node_name":"127.0.0.1:41676_dwl%2Frf", [junit4:junit4] 1> "base_url":"http://127.0.0.1:41676/dwl/rf"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.712; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Create collection nodes_used_collection with= numShards 2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.715; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Update state numShards=3D2 message=3D{ [junit4:junit4] 1> "operation":"state", [junit4:junit4] 1> "core_node_name":null, [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "shard":"shard2", [junit4:junit4] 1> "roles":null, [junit4:junit4] 1> "state":"down", [junit4:junit4] 1> "shard_state":"active", [junit4:junit4] 1> "core":"nodes_used_collection_shard2_replica1", [junit4:junit4] 1> "collection":"nodes_used_collection", [junit4:junit4] 1> "shard_range":null, [junit4:junit4] 1> "node_name":"127.0.0.1:41669_dwl%2Frf", [junit4:junit4] 1> "base_url":"http://127.0.0.1:41669/dwl/rf"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.727; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Update state numShards=3D2 message=3D{ [junit4:junit4] 1> "operation":"state", [junit4:junit4] 1> "core_node_name":null, [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "shard":"shard2", [junit4:junit4] 1> "roles":null, [junit4:junit4] 1> "state":"down", [junit4:junit4] 1> "shard_state":"active", [junit4:junit4] 1> "core":"nodes_used_collection_shard2_replica2", [junit4:junit4] 1> "collection":"nodes_used_collection", [junit4:junit4] 1> "shard_range":null, [junit4:junit4] 1> "node_name":"127.0.0.1:41673_dwl%2Frf", [junit4:junit4] 1> "base_url":"http://127.0.0.1:41673/dwl/rf"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.737; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Update state numShards=3D2 message=3D{ [junit4:junit4] 1> "operation":"state", [junit4:junit4] 1> "core_node_name":null, [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "shard":"shard1", [junit4:junit4] 1> "roles":null, [junit4:junit4] 1> "state":"down", [junit4:junit4] 1> "shard_state":"active", [junit4:junit4] 1> "core":"nodes_used_collection_shard1_replica1", [junit4:junit4] 1> "collection":"nodes_used_collection", [junit4:junit4] 1> "shard_range":null, [junit4:junit4] 1> "node_name":"127.0.0.1:41682_dwl%2Frf", [junit4:junit4] 1> "base_url":"http://127.0.0.1:41682/dwl/rf"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.746; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.746; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.746; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.746; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.746; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:17.746; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.154; org.apache.solr.core.= CoreContainer; registering core: nodes_used_collection_shard1_replica2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.155; org.apache.solr.cloud= .ZkController; Register replica - core:nodes_used_collection_shard1_replica= 2 address:http://127.0.0.1:41676/dwl/rf collection:nodes_used_collection sh= ard:shard1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.156; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/nodes_used_collection/leader_e= lect/shard1/election [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.162; org.apache.solr.core.= CoreContainer; registering core: nodes_used_collection_shard2_replica1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.162; org.apache.solr.cloud= .ZkController; Register replica - core:nodes_used_collection_shard2_replica= 1 address:http://127.0.0.1:41669/dwl/rf collection:nodes_used_collection sh= ard:shard2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.181; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/nodes_used_collection/leader_e= lect/shard2/election [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.193; org.apache.solr.cloud= .ShardLeaderElectionContext; Running the leader process. [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.197; org.apache.solr.cloud= .ShardLeaderElectionContext; Waiting until we see more replicas up: total= =3D2 found=3D1 timeoutin=3D179999 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.197; org.apache.solr.cloud= .ShardLeaderElectionContext; Running the leader process. [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.201; org.apache.solr.cloud= .ShardLeaderElectionContext; Waiting until we see more replicas up: total= =3D2 found=3D1 timeoutin=3D180000 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.376; org.apache.solr.core.= CoreContainer; registering core: nodes_used_collection_shard2_replica2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.377; org.apache.solr.cloud= .ZkController; Register replica - core:nodes_used_collection_shard2_replica= 2 address:http://127.0.0.1:41673/dwl/rf collection:nodes_used_collection sh= ard:shard2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.377; org.apache.solr.core.= CoreContainer; registering core: nodes_used_collection_shard1_replica1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.378; org.apache.solr.cloud= .ZkController; Register replica - core:nodes_used_collection_shard1_replica= 1 address:http://127.0.0.1:41682/dwl/rf collection:nodes_used_collection sh= ard:shard1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.698; org.apache.solr.cloud= .ShardLeaderElectionContext; Enough replicas found to continue. [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.699; org.apache.solr.cloud= .ShardLeaderElectionContext; I may be the new leader - try and sync [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.700; org.apache.solr.cloud= .SyncStrategy; Sync replicas to http://127.0.0.1:41676/dwl/rf/nodes_used_co= llection_shard1_replica2/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.700; org.apache.solr.updat= e.PeerSync; PeerSync: core=3Dnodes_used_collection_shard1_replica2 url=3Dht= tp://127.0.0.1:41676/dwl/rf START replicas=3D[http://127.0.0.1:41682/dwl/rf= /nodes_used_collection_shard1_replica1/] nUpdates=3D100 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.701; org.apache.solr.updat= e.PeerSync; PeerSync: core=3Dnodes_used_collection_shard1_replica2 url=3Dht= tp://127.0.0.1:41676/dwl/rf DONE. We have no versions. sync failed. [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.702; org.apache.solr.cloud= .SyncStrategy; Leader's attempt to sync with shard failed, moving to the ne= xt canidate [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.702; org.apache.solr.cloud= .ShardLeaderElectionContext; We failed sync, but we have no versions - we c= an't sync in that case - we were active before, so become leader anyway [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.703; org.apache.solr.cloud= .ShardLeaderElectionContext; I am the new leader: http://127.0.0.1:41676/dw= l/rf/nodes_used_collection_shard1_replica2/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.703; org.apache.solr.cloud= .ShardLeaderElectionContext; Enough replicas found to continue. [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.704; org.apache.solr.cloud= .ShardLeaderElectionContext; I may be the new leader - try and sync [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.704; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/nodes_used_collection/leaders/= shard1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.705; org.apache.solr.cloud= .SyncStrategy; Sync replicas to http://127.0.0.1:41669/dwl/rf/nodes_used_co= llection_shard2_replica1/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.706; org.apache.solr.updat= e.PeerSync; PeerSync: core=3Dnodes_used_collection_shard2_replica1 url=3Dht= tp://127.0.0.1:41669/dwl/rf START replicas=3D[http://127.0.0.1:41673/dwl/rf= /nodes_used_collection_shard2_replica2/] nUpdates=3D100 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.707; org.apache.solr.updat= e.PeerSync; PeerSync: core=3Dnodes_used_collection_shard2_replica1 url=3Dht= tp://127.0.0.1:41669/dwl/rf DONE. We have no versions. sync failed. [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.707; org.apache.solr.cloud= .SyncStrategy; Leader's attempt to sync with shard failed, moving to the ne= xt canidate [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.708; org.apache.solr.cloud= .ShardLeaderElectionContext; We failed sync, but we have no versions - we c= an't sync in that case - we were active before, so become leader anyway [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.708; org.apache.solr.cloud= .ShardLeaderElectionContext; I am the new leader: http://127.0.0.1:41669/dw= l/rf/nodes_used_collection_shard2_replica1/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.709; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/nodes_used_collection/leaders/= shard2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.711; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard1_replica1] webapp=3D/dwl/rf path=3D/= get params=3D{getVersions=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&vers= ion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:18.715; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard2_replica2] webapp=3D/dwl/rf path=3D/= get params=3D{getVersions=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&vers= ion=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.261; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cloud state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.280; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.280; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.280; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.280; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.280; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.280; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.297; org.apache.solr.cloud= .ZkController; We are http://127.0.0.1:41676/dwl/rf/nodes_used_collection_s= hard1_replica2/ and leader is http://127.0.0.1:41676/dwl/rf/nodes_used_coll= ection_shard1_replica2/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.297; org.apache.solr.cloud= .ZkController; No LogReplay needed for core=3Dnodes_used_collection_shard1_= replica2 baseURL=3Dhttp://127.0.0.1:41676/dwl/rf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.298; org.apache.solr.cloud= .ZkController; I am the leader, no recovery necessary [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.298; org.apache.solr.cloud= .ZkController; publishing core=3Dnodes_used_collection_shard1_replica2 stat= e=3Dactive [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.300; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cloud state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.301; org.apache.solr.core.= CoreContainer; Persisting cores config to /usr/home/hudson/hudson-slave/wor= kspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apach= e.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1365496331631/solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.304; org.apache.solr.cloud= .ZkController; We are http://127.0.0.1:41669/dwl/rf/nodes_used_collection_s= hard2_replica1/ and leader is http://127.0.0.1:41669/dwl/rf/nodes_used_coll= ection_shard2_replica1/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.304; org.apache.solr.cloud= .ZkController; No LogReplay needed for core=3Dnodes_used_collection_shard2_= replica1 baseURL=3Dhttp://127.0.0.1:41669/dwl/rf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.305; org.apache.solr.cloud= .ZkController; I am the leader, no recovery necessary [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.305; org.apache.solr.cloud= .ZkController; publishing core=3Dnodes_used_collection_shard2_replica1 stat= e=3Dactive [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.307; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cloud state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.308; org.apache.solr.core.= CoreContainer; Persisting cores config to /usr/home/hudson/hudson-slave/wor= kspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apach= e.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1365496329738/sol= r.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.313; org.apache.solr.core.= SolrXMLSerializer; Persisting cores config to /usr/home/hudson/hudson-slave= /workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.a= pache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1365496331631/solr.= xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.314; org.apache.solr.servl= et.SolrDispatchFilter; [admin] webapp=3Dnull path=3D/admin/cores params=3D{= numShards=3D2&shard=3Dshard1&name=3Dnodes_used_collection_shard1_replica2&a= ction=3DCREATE&collection=3Dnodes_used_collection&wt=3Djavabin&qt=3D/admin/= cores&version=3D2} status=3D0 QTime=3D3396=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.323; org.apache.solr.core.= SolrXMLSerializer; Persisting cores config to /usr/home/hudson/hudson-slave= /workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.a= pache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1365496329738= /solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.324; org.apache.solr.servl= et.SolrDispatchFilter; [admin] webapp=3Dnull path=3D/admin/cores params=3D{= numShards=3D2&shard=3Dshard2&name=3Dnodes_used_collection_shard2_replica1&a= ction=3DCREATE&collection=3Dnodes_used_collection&wt=3Djavabin&qt=3D/admin/= cores&version=3D2} status=3D0 QTime=3D3405=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.386; org.apache.solr.cloud= .ZkController; We are http://127.0.0.1:41682/dwl/rf/nodes_used_collection_s= hard1_replica1/ and leader is http://127.0.0.1:41676/dwl/rf/nodes_used_coll= ection_shard1_replica2/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.386; org.apache.solr.cloud= .ZkController; We are http://127.0.0.1:41673/dwl/rf/nodes_used_collection_s= hard2_replica2/ and leader is http://127.0.0.1:41669/dwl/rf/nodes_used_coll= ection_shard2_replica1/ [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.387; org.apache.solr.cloud= .ZkController; No LogReplay needed for core=3Dnodes_used_collection_shard1_= replica1 baseURL=3Dhttp://127.0.0.1:41682/dwl/rf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.388; org.apache.solr.cloud= .ZkController; No LogReplay needed for core=3Dnodes_used_collection_shard2_= replica2 baseURL=3Dhttp://127.0.0.1:41673/dwl/rf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.389; org.apache.solr.cloud= .ZkController; Core needs to recover:nodes_used_collection_shard2_replica2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.388; org.apache.solr.cloud= .ZkController; Core needs to recover:nodes_used_collection_shard1_replica1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.389; org.apache.solr.updat= e.DefaultSolrCoreState; Running recovery - first canceling any ongoing reco= very [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.389; org.apache.solr.updat= e.DefaultSolrCoreState; Running recovery - first canceling any ongoing reco= very [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.390; org.apache.solr.cloud= .RecoveryStrategy; Starting recovery process. core=3Dnodes_used_collection= _shard1_replica1 recoveringAfterStartup=3Dtrue [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.391; org.apache.solr.cloud= .RecoveryStrategy; Starting recovery process. core=3Dnodes_used_collection= _shard2_replica2 recoveringAfterStartup=3Dtrue [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.391; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cloud state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.391; org.apache.solr.cloud= .RecoveryStrategy; ###### startupVersions=3D[] [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.391; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cloud state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.391; org.apache.solr.cloud= .RecoveryStrategy; ###### startupVersions=3D[] [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.392; org.apache.solr.core.= CoreContainer; Persisting cores config to /usr/home/hudson/hudson-slave/wor= kspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apach= e.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1365496334500/solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.392; org.apache.solr.cloud= .ZkController; publishing core=3Dnodes_used_collection_shard2_replica2 stat= e=3Drecovering [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.393; org.apache.solr.core.= CoreContainer; Persisting cores config to /usr/home/hudson/hudson-slave/wor= kspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apach= e.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1365496330221/solr.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.393; org.apache.solr.cloud= .ZkController; publishing core=3Dnodes_used_collection_shard1_replica1 stat= e=3Drecovering [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.395; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.396; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.409; org.apache.solr.core.= SolrXMLSerializer; Persisting cores config to /usr/home/hudson/hudson-slave= /workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.a= pache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1365496334500/solr.= xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.411; org.apache.solr.servl= et.SolrDispatchFilter; [admin] webapp=3Dnull path=3D/admin/cores params=3D{= numShards=3D2&shard=3Dshard1&name=3Dnodes_used_collection_shard1_replica1&a= ction=3DCREATE&collection=3Dnodes_used_collection&wt=3Djavabin&qt=3D/admin/= cores&version=3D2} status=3D0 QTime=3D3493=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.411; org.apache.solr.core.= SolrXMLSerializer; Persisting cores config to /usr/home/hudson/hudson-slave= /workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.a= pache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1365496330221/solr.= xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.414; org.apache.solr.servl= et.SolrDispatchFilter; [admin] webapp=3Dnull path=3D/admin/cores params=3D{= numShards=3D2&shard=3Dshard2&name=3Dnodes_used_collection_shard2_replica2&a= ction=3DCREATE&collection=3Dnodes_used_collection&wt=3Djavabin&qt=3D/admin/= cores&version=3D2} status=3D0 QTime=3D3495=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.414; org.apache.solr.cloud= .OverseerCollectionProcessor; Finished create command on all shards for col= lection: nodes_used_collection [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.418; org.apache.solr.handl= er.admin.CoreAdminHandler; Going to wait for coreNodeName: 127.0.0.1:41682_= dwl%2Frf_nodes_used_collection_shard1_replica1, state: recovering, checkLiv= e: true, onlyIfLeader: true [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.418; org.apache.solr.handl= er.admin.CoreAdminHandler; Going to wait for coreNodeName: 127.0.0.1:41673_= dwl%2Frf_nodes_used_collection_shard2_replica2, state: recovering, checkLiv= e: true, onlyIfLeader: true [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.418; org.apache.solr.cloud= .DistributedQueue$LatchChildWatcher; Watcher fired on path: /overseer/colle= ction-queue-work/qnr-0000000000 state: SyncConnected type NodeDataChanged [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.428; org.apache.solr.cloud= .DistributedQueue$LatchChildWatcher; Watcher fired on path: /overseer/colle= ction-queue-work state: SyncConnected type NodeChildrenChanged [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.428; org.apache.solr.cloud= .OverseerCollectionProcessor; Overseer Collection Processor: Message id:/ov= erseer/collection-queue-work/qn-0000000000 complete, response:{success=3D{n= ull=3D{responseHeader=3D{status=3D0,QTime=3D3396},core=3Dnodes_used_collect= ion_shard1_replica2,saved=3D/usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.C= ollectionsAPIDistributedZkTest-jetty2-1365496331631/solr.xml},null=3D{respo= nseHeader=3D{status=3D0,QTime=3D3405},core=3Dnodes_used_collection_shard2_r= eplica1,saved=3D/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAP= IDistributedZkTest-controljetty-1365496329738/solr.xml},null=3D{responseHea= der=3D{status=3D0,QTime=3D3493},core=3Dnodes_used_collection_shard1_replica= 1,saved=3D/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-jav= a7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistr= ibutedZkTest-jetty4-1365496334500/solr.xml},null=3D{responseHeader=3D{statu= s=3D0,QTime=3D3495},core=3Dnodes_used_collection_shard2_replica2,saved=3D/u= sr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest= -jetty1-1365496330221/solr.xml}}} [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.431; org.apache.solr.servl= et.SolrDispatchFilter; [admin] webapp=3Dnull path=3D/admin/collections para= ms=3D{numShards=3D2&name=3Dnodes_used_collection&replicationFactor=3D2&acti= on=3DCREATE&wt=3Djavabin&version=3D2} status=3D0 QTime=3D3526=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.432; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config: [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.433; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.435; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@11a9d614 name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.436; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.437; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cluster state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.440; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config: [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.443; org.apache.solr.commo= n.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.445; org.apache.solr.commo= n.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionM= anager@35dff82d name:ZooKeeperConnection Watcher:127.0.0.1:41662/solr got e= vent WatchedEvent state:SyncConnected type:None path:null path:null type:No= ne [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.445; org.apache.solr.commo= n.cloud.ConnectionManager; Client is connected to ZooKeeper [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.446; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cluster state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.459; org.apache.solr.handl= er.admin.CollectionsHandler; Creating Collection : numShards=3D5&maxShardsP= erNode=3D6&name=3Dawholynewcollection_0&replicationFactor=3D5&action=3DCREA= TE&wt=3Djavabin&version=3D2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.461; org.apache.solr.cloud= .DistributedQueue$LatchChildWatcher; Watcher fired on path: /overseer/colle= ction-queue-work state: SyncConnected type NodeChildrenChanged [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.462; org.apache.solr.cloud= .OverseerCollectionProcessor; Overseer Collection Processor: Get the messag= e id:/overseer/collection-queue-work/qn-0000000002 message:{ [junit4:junit4] 1> "operation":"createcollection", [junit4:junit4] 1> "numShards":"5", [junit4:junit4] 1> "maxShardsPerNode":"6", [junit4:junit4] 1> "createNodeSet":null, [junit4:junit4] 1> "name":"awholynewcollection_0", [junit4:junit4] 1> "replicationFactor":"5"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.463; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard1_r= eplica1 as part of slice shard1 of collection awholynewcollection_0 on 127.= 0.0.1:41679_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.464; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard1_r= eplica2 as part of slice shard1 of collection awholynewcollection_0 on 127.= 0.0.1:41676_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.464; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard1_r= eplica3 as part of slice shard1 of collection awholynewcollection_0 on 127.= 0.0.1:41669_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.465; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard1_r= eplica4 as part of slice shard1 of collection awholynewcollection_0 on 127.= 0.0.1:41673_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.465; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard1_replica1' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty3-1365496333057/awholynewcollection_0_shard1_repli= ca1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.466; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.465; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard1_r= eplica5 as part of slice shard1 of collection awholynewcollection_0 on 127.= 0.0.1:41682_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.466; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard1_replica2' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty2-1365496331631/awholynewcollection_0_shard1_repli= ca2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.467; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.467; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard2_r= eplica1 as part of slice shard2 of collection awholynewcollection_0 on 127.= 0.0.1:41679_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.468; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.468; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.467; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.469; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard2_replica1' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty3-1365496333057/awholynewcollection_0_shard2_repli= ca1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.470; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.469; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard1_replica5' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty4-1365496334500/awholynewcollection_0_shard1_repli= ca5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.471; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.468; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard2_r= eplica2 as part of slice shard2 of collection awholynewcollection_0 on 127.= 0.0.1:41676_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.467; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard1_replica4' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty1-1365496330221/awholynewcollection_0_shard1_repli= ca4 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.472; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.467; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard1_replica3' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-controljetty-1365496329738/awholynewcollection_0_shard1= _replica3 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.473; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard2_replica2' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty2-1365496331631/awholynewcollection_0_shard2_repli= ca2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.474; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.472; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard2_r= eplica3 as part of slice shard2 of collection awholynewcollection_0 on 127.= 0.0.1:41669_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.475; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.472; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.471; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.470; org.apache.solr.cloud= .ZkController; Only one config set found in zk - using it:conf1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.470; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.476; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.476; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.476; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard2_replica3' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-controljetty-1365496329738/awholynewcollection_0_shard2= _replica3 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.476; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.475; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.475; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard2_r= eplica4 as part of slice shard2 of collection awholynewcollection_0 on 127.= 0.0.1:41673_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.473; org.apache.solr.cloud= .ZkController; Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.473; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.481; org.apache.solr.cloud= .ZkController; Looking for collection configName [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.481; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard2_r= eplica5 as part of slice shard2 of collection awholynewcollection_0 on 127.= 0.0.1:41682_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.480; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.480; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.480; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.480; org.apache.solr.cloud= .ZkController; Only one config set found in zk - using it:conf1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.479; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.478; org.apache.solr.cloud= .ZkController; Only one config set found in zk - using it:conf1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.485; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.486; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.485; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.484; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.484; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard2_replica5' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty4-1365496334500/awholynewcollection_0_shard2_repli= ca5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.482; org.apache.solr.commo= n.cloud.SolrZkClient; makePath: /collections/awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.482; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard3_r= eplica1 as part of slice shard3 of collection awholynewcollection_0 on 127.= 0.0.1:41679_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.482; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.489; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.482; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard2_replica4' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty1-1365496330221/awholynewcollection_0_shard2_repli= ca4 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.490; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.489; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard3_r= eplica2 as part of slice shard3 of collection awholynewcollection_0 on 127.= 0.0.1:41676_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.488; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.486; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.486; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-136549= 6331631/awholynewcollection_0_shard1_replica2/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.492; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard3_replica2' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty2-1365496331631/awholynewcollection_0_shard3_repli= ca2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.492; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.491; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard3_r= eplica3 as part of slice shard3 of collection awholynewcollection_0 on 127.= 0.0.1:41669_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.490; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard3_replica1' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty3-1365496333057/awholynewcollection_0_shard3_repli= ca1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.494; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.494; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard3_r= eplica4 as part of slice shard3 of collection awholynewcollection_0 on 127.= 0.0.1:41673_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.493; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.495; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard3_r= eplica5 as part of slice shard3 of collection awholynewcollection_0 on 127.= 0.0.1:41682_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.495; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard3_replica3' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-controljetty-1365496329738/awholynewcollection_0_shard3= _replica3 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.497; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.498; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.496; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard3_replica4' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty1-1365496330221/awholynewcollection_0_shard3_repli= ca4 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.496; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.496; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard4_r= eplica1 as part of slice shard4 of collection awholynewcollection_0 on 127.= 0.0.1:41679_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.496; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.500; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard4_r= eplica2 as part of slice shard4 of collection awholynewcollection_0 on 127.= 0.0.1:41676_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.499; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-136549= 6331631/awholynewcollection_0_shard2_replica2/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.499; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.498; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard3_replica5' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty4-1365496334500/awholynewcollection_0_shard3_repli= ca5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.498; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.498; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-= 1365496329738/awholynewcollection_0_shard2_replica3/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.498; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-= 1365496329738/awholynewcollection_0_shard1_replica3/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.498; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.506; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.497; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136549= 6334500/awholynewcollection_0_shard1_replica5/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.497; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.497; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.509; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136549= 6334500/awholynewcollection_0_shard2_replica5/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.508; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136549= 6330221/awholynewcollection_0_shard1_replica4/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.508; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-136549= 6333057/awholynewcollection_0_shard1_replica1/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.508; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.508; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-136549= 6333057/awholynewcollection_0_shard3_replica1/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.506; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.504; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.502; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.502; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard4_replica2' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty2-1365496331631/awholynewcollection_0_shard4_repli= ca2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.501; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard4_replica1' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty3-1365496333057/awholynewcollection_0_shard4_repli= ca1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.515; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.501; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard4_r= eplica3 as part of slice shard4 of collection awholynewcollection_0 on 127.= 0.0.1:41669_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.500; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.516; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard4_r= eplica4 as part of slice shard4 of collection awholynewcollection_0 on 127.= 0.0.1:41673_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.516; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.515; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-136549= 6333057/awholynewcollection_0_shard2_replica1/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.514; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.514; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.514; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-136549= 6331631/awholynewcollection_0_shard3_replica2/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.512; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.510; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.521; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.519; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.519; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.518; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard4_replica4' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty1-1365496330221/awholynewcollection_0_shard4_repli= ca4 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.525; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.518; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136549= 6330221/awholynewcollection_0_shard2_replica4/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.526; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.518; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard4_replica3' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-controljetty-1365496329738/awholynewcollection_0_shard4= _replica3 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.517; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.517; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard4_r= eplica5 as part of slice shard4 of collection awholynewcollection_0 on 127.= 0.0.1:41682_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.527; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.527; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.525; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136549= 6334500/awholynewcollection_0_shard3_replica5/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.525; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-= 1365496329738/awholynewcollection_0_shard3_replica3/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.524; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.523; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136549= 6330221/awholynewcollection_0_shard3_replica4/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.531; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard4_replica5' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty4-1365496334500/awholynewcollection_0_shard4_repli= ca5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.531; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-136549= 6333057/awholynewcollection_0_shard4_replica1/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.530; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.530; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard5_r= eplica1 as part of slice shard5 of collection awholynewcollection_0 on 127.= 0.0.1:41679_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.535; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.535; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136549= 6330221/awholynewcollection_0_shard4_replica4/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.534; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.536; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-136549= 6331631/awholynewcollection_0_shard4_replica2/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.536; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard5_r= eplica2 as part of slice shard5 of collection awholynewcollection_0 on 127.= 0.0.1:41676_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.539; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-= 1365496329738/awholynewcollection_0_shard4_replica3/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.538; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.538; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard5_replica1' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty3-1365496333057/awholynewcollection_0_shard5_repli= ca1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.541; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard5_replica2' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty2-1365496331631/awholynewcollection_0_shard5_repli= ca2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.540; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.539; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard5_r= eplica3 as part of slice shard5 of collection awholynewcollection_0 on 127.= 0.0.1:41669_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.542; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.541; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.544; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.545; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.543; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard5_r= eplica4 as part of slice shard5 of collection awholynewcollection_0 on 127.= 0.0.1:41673_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.545; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.546; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.545; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136549= 6334500/awholynewcollection_0_shard4_replica5/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.545; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard5_replica3' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-controljetty-1365496329738/awholynewcollection_0_shard5= _replica3 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.548; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.548; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard5_replica4' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty1-1365496330221/awholynewcollection_0_shard5_repli= ca4 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.546; org.apache.solr.cloud= .OverseerCollectionProcessor; Creating shard awholynewcollection_0_shard5_r= eplica5 as part of slice shard5 of collection awholynewcollection_0 on 127.= 0.0.1:41682_dwl%2Frf [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.550; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.548; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-136549= 6331631/awholynewcollection_0_shard5_replica2/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.551; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-136549= 6333057/awholynewcollection_0_shard5_replica1/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.553; org.apache.solr.core.= CoreContainer; Creating SolrCore 'awholynewcollection_0_shard5_replica5' us= ing instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsA= PIDistributedZkTest-jetty4-1365496334500/awholynewcollection_0_shard5_repli= ca5 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.553; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.554; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.552; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.554; org.apache.solr.cloud= .ZkController; Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.555; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.557; org.apache.solr.cloud= .ZkController; Collection zkNode exists [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.558; org.apache.solr.cloud= .ZkController; Load collection config from:/collections/awholynewcollection= _0 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.559; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-= 1365496329738/awholynewcollection_0_shard5_replica3/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.562; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136549= 6330221/awholynewcollection_0_shard5_replica4/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.563; org.apache.solr.core.= SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson= /hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/te= st/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136549= 6334500/awholynewcollection_0_shard5_replica5/' [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.673; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.675; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.677; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.677; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.678; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.700; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.703; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.718; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.724; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.732; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.766; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.766; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.788; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.788; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.824; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.827; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.827; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.875; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.880; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.881; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.881; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.928; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.953; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.962; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.970; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:19.975; org.apache.solr.core.= SolrConfig; Using Lucene MatchVersion: LUCENE_43 [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.000; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.000; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.006; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.008; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.013; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.015; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.016; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.024; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.027; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.028; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.028; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.030; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.033; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.035; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.043; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.044; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.045; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.045; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.047; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.053; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.055; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.056; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.063; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.065; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.094; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.095; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.095; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.100; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.119; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.121; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.125; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.130; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.131; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.138; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.139; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.143; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.156; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.160; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.161; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.162; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.163; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.165; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.180; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.186; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.194; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.199; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.224; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.228; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.231; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.236; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.240; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.245; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.246; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.246; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.247; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.249; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.249; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.251; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.253; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.256; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.259; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.262; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.264; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.266; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.271; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.357; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.361; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.363; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.365; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.367; org.apache.solr.core.= SolrConfig; Loaded SolrConfig: solrconfig.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.370; org.apache.solr.schem= a.IndexSchema; Reading Solr Schema from schema.xml [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.376; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.383; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.389; org.apache.solr.schem= a.IndexSchema; Schema name=3Dtest [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.796; org.apache.solr.commo= n.cloud.ZkStateReader; Updating cloud state from ZooKeeper...=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.797; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Update state numShards=3D2 message=3D{ [junit4:junit4] 1> "operation":"state", [junit4:junit4] 1> "core_node_name":"127.0.0.1:41676_dwl%2Frf_nodes_use= d_collection_shard1_replica2", [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "shard":"shard1", [junit4:junit4] 1> "roles":null, [junit4:junit4] 1> "state":"active", [junit4:junit4] 1> "shard_state":"active", [junit4:junit4] 1> "core":"nodes_used_collection_shard1_replica2", [junit4:junit4] 1> "collection":"nodes_used_collection", [junit4:junit4] 1> "shard_range":null, [junit4:junit4] 1> "node_name":"127.0.0.1:41676_dwl%2Frf", [junit4:junit4] 1> "base_url":"http://127.0.0.1:41676/dwl/rf"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.813; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Update state numShards=3D2 message=3D{ [junit4:junit4] 1> "operation":"state", [junit4:junit4] 1> "core_node_name":"127.0.0.1:41669_dwl%2Frf_nodes_use= d_collection_shard2_replica1", [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "shard":"shard2", [junit4:junit4] 1> "roles":null, [junit4:junit4] 1> "state":"active", [junit4:junit4] 1> "shard_state":"active", [junit4:junit4] 1> "core":"nodes_used_collection_shard2_replica1", [junit4:junit4] 1> "collection":"nodes_used_collection", [junit4:junit4] 1> "shard_range":null, [junit4:junit4] 1> "node_name":"127.0.0.1:41669_dwl%2Frf", [junit4:junit4] 1> "base_url":"http://127.0.0.1:41669/dwl/rf"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.831; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Update state numShards=3D2 message=3D{ [junit4:junit4] 1> "operation":"state", [junit4:junit4] 1> "core_node_name":"127.0.0.1:41673_dwl%2Frf_nodes_use= d_collection_shard2_replica2", [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "shard":"shard2", [junit4:junit4] 1> "roles":null, [junit4:junit4] 1> "state":"recovering", [junit4:junit4] 1> "shard_state":"active", [junit4:junit4] 1> "core":"nodes_used_collection_shard2_replica2", [junit4:junit4] 1> "collection":"nodes_used_collection", [junit4:junit4] 1> "shard_range":null, [junit4:junit4] 1> "node_name":"127.0.0.1:41673_dwl%2Frf", [junit4:junit4] 1> "base_url":"http://127.0.0.1:41673/dwl/rf"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.848; org.apache.solr.cloud= .Overseer$ClusterStateUpdater; Update state numShards=3D2 message=3D{ [junit4:junit4] 1> "operation":"state", [junit4:junit4] 1> "core_node_name":"127.0.0.1:41682_dwl%2Frf_nodes_use= d_collection_shard1_replica1", [junit4:junit4] 1> "numShards":"2", [junit4:junit4] 1> "shard":"shard1", [junit4:junit4] 1> "roles":null, [junit4:junit4] 1> "state":"recovering", [junit4:junit4] 1> "shard_state":"active", [junit4:junit4] 1> "core":"nodes_used_collection_shard1_replica1", [junit4:junit4] 1> "collection":"nodes_used_collection", [junit4:junit4] 1> "shard_range":null, [junit4:junit4] 1> "node_name":"127.0.0.1:41682_dwl%2Frf", [junit4:junit4] 1> "base_url":"http://127.0.0.1:41682/dwl/rf"} [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.861; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.861; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.861; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.861; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.863; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.861; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.862; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:20.862; org.apache.solr.commo= n.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncCon= nected type:NodeDataChanged path:/clusterstate.json, has occurred - updatin= g... (live nodes size: 5) [junit4:junit4] 1> INFO - 2013-04-09 08:32:21.421; org.apache.solr.handl= er.admin.CoreAdminHandler; Waited coreNodeName: 127.0.0.1:41682_dwl%2Frf_no= des_used_collection_shard1_replica1, state: recovering, checkLive: true, on= lyIfLeader: true for: 2 seconds. [junit4:junit4] 1> INFO - 2013-04-09 08:32:21.422; org.apache.solr.handl= er.admin.CoreAdminHandler; Waited coreNodeName: 127.0.0.1:41673_dwl%2Frf_no= des_used_collection_shard2_replica2, state: recovering, checkLive: true, on= lyIfLeader: true for: 2 seconds. [junit4:junit4] 1> INFO - 2013-04-09 08:32:21.422; org.apache.solr.servl= et.SolrDispatchFilter; [admin] webapp=3Dnull path=3D/admin/cores params=3D{= coreNodeName=3D127.0.0.1:41682_dwl%252Frf_nodes_used_collection_shard1_repl= ica1&state=3Drecovering&nodeName=3D127.0.0.1:41682_dwl%252Frf&action=3DPREP= RECOVERY&checkLive=3Dtrue&core=3Dnodes_used_collection_shard1_replica2&wt= =3Djavabin&onlyIfLeader=3Dtrue&version=3D2} status=3D0 QTime=3D2004=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:21.423; org.apache.solr.servl= et.SolrDispatchFilter; [admin] webapp=3Dnull path=3D/admin/cores params=3D{= coreNodeName=3D127.0.0.1:41673_dwl%252Frf_nodes_used_collection_shard2_repl= ica2&state=3Drecovering&nodeName=3D127.0.0.1:41673_dwl%252Frf&action=3DPREP= RECOVERY&checkLive=3Dtrue&core=3Dnodes_used_collection_shard2_replica1&wt= =3Djavabin&onlyIfLeader=3Dtrue&version=3D2} status=3D0 QTime=3D2005=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.454; org.apache.solr.cloud= .RecoveryStrategy; Attempting to PeerSync from http://127.0.0.1:41676/dwl/r= f/nodes_used_collection_shard1_replica2/ core=3Dnodes_used_collection_shard= 1_replica1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.454; org.apache.solr.updat= e.PeerSync; PeerSync: core=3Dnodes_used_collection_shard1_replica1 url=3Dht= tp://127.0.0.1:41682/dwl/rf START replicas=3D[http://127.0.0.1:41676/dwl/rf= /nodes_used_collection_shard1_replica2/] nUpdates=3D100 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.455; org.apache.solr.cloud= .RecoveryStrategy; Attempting to PeerSync from http://127.0.0.1:41669/dwl/r= f/nodes_used_collection_shard2_replica1/ core=3Dnodes_used_collection_shard= 2_replica2 - recoveringAfterStartup=3Dtrue [junit4:junit4] 1> WARN - 2013-04-09 08:32:23.455; org.apache.solr.updat= e.PeerSync; no frame of reference to tell of we've missed updates [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.456; org.apache.solr.cloud= .RecoveryStrategy; PeerSync Recovery was not successful - trying replicatio= n. core=3Dnodes_used_collection_shard1_replica1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.456; org.apache.solr.cloud= .RecoveryStrategy; Starting Replication Recovery. core=3Dnodes_used_collect= ion_shard1_replica1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.457; org.apache.solr.cloud= .RecoveryStrategy; Begin buffering updates. core=3Dnodes_used_collection_sh= ard1_replica1 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.457; org.apache.solr.updat= e.UpdateLog; Starting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog= =3Dnull} [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.455; org.apache.solr.updat= e.PeerSync; PeerSync: core=3Dnodes_used_collection_shard2_replica2 url=3Dht= tp://127.0.0.1:41673/dwl/rf START replicas=3D[http://127.0.0.1:41669/dwl/rf= /nodes_used_collection_shard2_replica1/] nUpdates=3D100 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.457; org.apache.solr.cloud= .RecoveryStrategy; Attempting to replicate from http://127.0.0.1:41676/dwl/= rf/nodes_used_collection_shard1_replica2/. core=3Dnodes_used_collection_sha= rd1_replica1 [junit4:junit4] 1> WARN - 2013-04-09 08:32:23.459; org.apache.solr.updat= e.PeerSync; no frame of reference to tell of we've missed updates [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.459; org.apache.solr.cloud= .RecoveryStrategy; PeerSync Recovery was not successful - trying replicatio= n. core=3Dnodes_used_collection_shard2_replica2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.460; org.apache.solr.cloud= .RecoveryStrategy; Starting Replication Recovery. core=3Dnodes_used_collect= ion_shard2_replica2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.460; org.apache.solr.cloud= .RecoveryStrategy; Begin buffering updates. core=3Dnodes_used_collection_sh= ard2_replica2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.460; org.apache.solr.updat= e.UpdateLog; Starting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog= =3Dnull} [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.459; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.461; org.apache.solr.cloud= .RecoveryStrategy; Attempting to replicate from http://127.0.0.1:41669/dwl/= rf/nodes_used_collection_shard2_replica1/. core=3Dnodes_used_collection_sha= rd2_replica2 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.462; org.apache.solr.clien= t.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnection= s=3D128&maxConnectionsPerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.485; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard1_replica2] webapp=3D/dwl/rf path=3D/= get params=3D{getVersions=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&vers= ion=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.492; org.apache.solr.core.= SolrCore; [nodes_used_collection_shard2_replica1] webapp=3D/dwl/rf path=3D/= get params=3D{getVersions=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&vers= ion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.517; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dfalse= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommi= t=3Dfalse} [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.522; org.apache.solr.updat= e.DirectUpdateHandler2; start commit{,optimize=3Dfalse,openSearcher=3Dfalse= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommi= t=3Dfalse} [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.527; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@6972556c lockFactory=3Dorg.apache.lucene.store.NativeFSLockFa= ctory@13300878),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.529; org.apache.solr.core.= SolrDeletionPolicy; newest commit =3D 1[segments_1] [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.530; org.apache.solr.core.= SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@6972556c lockFactory=3Dorg.apache.lucene.store.NativeFSLockFa= ctory@13300878),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 1> =09commit{dir=3DMockDirWrapper(org.apache.lucene.store= .RAMDirectory@6972556c lockFactory=3Dorg.apache.lucene.store.NativeFSLockFa= ctory@13300878),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 1> INFO - 2013-04-09 08:32:23.531; org.apache.solr.core.= SolrDeletionPolicy; newest commit =3D 2[segments_2] [junit4:junit4] 1> I [...truncated too long message...] mpl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:= 294) [junit4:junit4] > at org.apache.http.impl.client.DefaultRequestD= irector.tryConnect(DefaultRequestDirector.java:645) [junit4:junit4] > at org.apache.http.impl.client.DefaultRequestD= irector.execute(DefaultRequestDirector.java:480) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:906) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:805) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:784) [junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrS= erver.request(HttpSolrServer.java:353) [junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrS= erver.request(HttpSolrServer.java:181) [junit4:junit4] > at org.apache.solr.cloud.SyncStrategy$1.run(Sy= ncStrategy.java:298) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.run= Worker(ThreadPoolExecutor.java:1145) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor$Wor= ker.run(ThreadPoolExecutor.java:615) [junit4:junit4] > at java.lang.Thread.run(Thread.java:722) [junit4:junit4] > 2) Thread[id=3D4107, name=3DrecoveryCmdExecutor-161= 2-thread-1, state=3DRUNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4:junit4] > at java.net.PlainSocketImpl.socketConnect(Nati= ve Method) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.doConnect(= AbstractPlainSocketImpl.java:339) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.connectToA= ddress(AbstractPlainSocketImpl.java:200) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.connect(Ab= stractPlainSocketImpl.java:182) [junit4:junit4] > at java.net.SocksSocketImpl.connect(SocksSocke= tImpl.java:391) [junit4:junit4] > at java.net.Socket.connect(Socket.java:579) [junit4:junit4] > at org.apache.http.conn.scheme.PlainSocketFact= ory.connectSocket(PlainSocketFactory.java:127) [junit4:junit4] > at org.apache.http.impl.conn.DefaultClientConn= ectionOperator.openConnection(DefaultClientConnectionOperator.java:180) [junit4:junit4] > at org.apache.http.impl.conn.ManagedClientConn= ectionImpl.open(ManagedClientConnectionImpl.java:294) [junit4:junit4] > at org.apache.http.impl.client.DefaultRequestD= irector.tryConnect(DefaultRequestDirector.java:645) [junit4:junit4] > at org.apache.http.impl.client.DefaultRequestD= irector.execute(DefaultRequestDirector.java:480) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:906) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:805) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:784) [junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrS= erver.request(HttpSolrServer.java:353) [junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrS= erver.request(HttpSolrServer.java:181) [junit4:junit4] > at org.apache.solr.cloud.SyncStrategy$1.run(Sy= ncStrategy.java:298) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.run= Worker(ThreadPoolExecutor.java:1145) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor$Wor= ker.run(ThreadPoolExecutor.java:615) [junit4:junit4] > at java.lang.Thread.run(Thread.java:722) [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([582D368C62EB2= 001]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: Th= ere are still zombie threads that couldn't be terminated: [junit4:junit4] > 1) Thread[id=3D4109, name=3DrecoveryCmdExecutor-161= 2-thread-2, state=3DRUNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4:junit4] > at java.net.PlainSocketImpl.socketConnect(Nati= ve Method) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.doConnect(= AbstractPlainSocketImpl.java:339) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.connectToA= ddress(AbstractPlainSocketImpl.java:200) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.connect(Ab= stractPlainSocketImpl.java:182) [junit4:junit4] > at java.net.SocksSocketImpl.connect(SocksSocke= tImpl.java:391) [junit4:junit4] > at java.net.Socket.connect(Socket.java:579) [junit4:junit4] > at org.apache.http.conn.scheme.PlainSocketFact= ory.connectSocket(PlainSocketFactory.java:127) [junit4:junit4] > at org.apache.http.impl.conn.DefaultClientConn= ectionOperator.openConnection(DefaultClientConnectionOperator.java:180) [junit4:junit4] > at org.apache.http.impl.conn.ManagedClientConn= ectionImpl.open(ManagedClientConnectionImpl.java:294) [junit4:junit4] > at org.apache.http.impl.client.DefaultRequestD= irector.tryConnect(DefaultRequestDirector.java:645) [junit4:junit4] > at org.apache.http.impl.client.DefaultRequestD= irector.execute(DefaultRequestDirector.java:480) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:906) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:805) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:784) [junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrS= erver.request(HttpSolrServer.java:353) [junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrS= erver.request(HttpSolrServer.java:181) [junit4:junit4] > at org.apache.solr.cloud.SyncStrategy$1.run(Sy= ncStrategy.java:298) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.run= Worker(ThreadPoolExecutor.java:1145) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor$Wor= ker.run(ThreadPoolExecutor.java:615) [junit4:junit4] > at java.lang.Thread.run(Thread.java:722) [junit4:junit4] > 2) Thread[id=3D4107, name=3DrecoveryCmdExecutor-161= 2-thread-1, state=3DRUNNABLE, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4:junit4] > at java.net.PlainSocketImpl.socketConnect(Nati= ve Method) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.doConnect(= AbstractPlainSocketImpl.java:339) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.connectToA= ddress(AbstractPlainSocketImpl.java:200) [junit4:junit4] > at java.net.AbstractPlainSocketImpl.connect(Ab= stractPlainSocketImpl.java:182) [junit4:junit4] > at java.net.SocksSocketImpl.connect(SocksSocke= tImpl.java:391) [junit4:junit4] > at java.net.Socket.connect(Socket.java:579) [junit4:junit4] > at org.apache.http.conn.scheme.PlainSocketFact= ory.connectSocket(PlainSocketFactory.java:127) [junit4:junit4] > at org.apache.http.impl.conn.DefaultClientConn= ectionOperator.openConnection(DefaultClientConnectionOperator.java:180) [junit4:junit4] > at org.apache.http.impl.conn.ManagedClientConn= ectionImpl.open(ManagedClientConnectionImpl.java:294) [junit4:junit4] > at org.apache.http.impl.client.DefaultRequestD= irector.tryConnect(DefaultRequestDirector.java:645) [junit4:junit4] > at org.apache.http.impl.client.DefaultRequestD= irector.execute(DefaultRequestDirector.java:480) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:906) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:805) [junit4:junit4] > at org.apache.http.impl.client.AbstractHttpCli= ent.execute(AbstractHttpClient.java:784) [junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrS= erver.request(HttpSolrServer.java:353) [junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrS= erver.request(HttpSolrServer.java:181) [junit4:junit4] > at org.apache.solr.cloud.SyncStrategy$1.run(Sy= ncStrategy.java:298) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.run= Worker(ThreadPoolExecutor.java:1145) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor$Wor= ker.run(ThreadPoolExecutor.java:615) [junit4:junit4] > at java.lang.Thread.run(Thread.java:722) [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([582D368C62EB2= 001]:0) [junit4:junit4] Completed on J0 in 351.08s, 1 test, 1 failure, 2 errors <<<= FAILURES! [...truncated 413 lines...] BUILD FAILED /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/build.x= ml:381: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/build.x= ml:361: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/build.x= ml:39: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/bu= ild.xml:183: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/co= mmon-build.xml:448: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/= common-build.xml:1234: The following error occurred while executing this li= ne: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/= common-build.xml:878: There were test failures: 288 suites, 1186 tests, 2 s= uite-level errors, 1 failure, 422 ignored (7 assumptions) Total time: 57 minutes 44 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_968_1751208919.1365496725736 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_968_1751208919.1365496725736--