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 73863DFDE for ; Sun, 16 Sep 2012 05:26:51 +0000 (UTC) Received: (qmail 14922 invoked by uid 500); 16 Sep 2012 05:26:49 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 14737 invoked by uid 500); 16 Sep 2012 05:26:47 -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 14686 invoked by uid 99); 16 Sep 2012 05:26:45 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 16 Sep 2012 05:26:45 +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; Sun, 16 Sep 2012 05:26:24 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id DD395C00A0 for ; Sun, 16 Sep 2012 05:26:01 +0000 (UTC) Date: Sun, 16 Sep 2012 05:25:50 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <1969874640.446.1347773161842.JavaMail.hudson@aegis.apache.org> Subject: [JENKINS] Lucene-Solr-Tests-trunk-Java6 - Build # 15243 - Failure MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_445_2031706218.1347773150456" ------=_Part_445_2031706218.1347773150456 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java6/15243/ 2 tests failed. REGRESSION: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearc= h Error Message: Shard still reported as live in zk Stack Trace: java.lang.AssertionError: Shard still reported as live in zk =09at __randomizedtesting.SeedInfo.seed([1075936FEF80A8C4:91931D7798DFC8F8]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(= AbstractFullDistribZkTestBase.java:1220) =09at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSome= DocsAndRecover(BasicDistributedZk2Test.java:190) =09at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributed= Zk2Test.java:83) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:695) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:616) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at java.lang.Thread.run(Thread.java:679) REGRESSION: org.apache.solr.cloud.SyncSliceTest.testDistribSearch Error Message: No live SolrServers available to handle this request Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers avail= able to handle this request =09at __randomizedtesting.SeedInfo.seed([1075936FEF80A8C4:91931D7798DFC8F8]= :0) =09at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolr= Server.java:323) =09at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrSe= rver.java:257) =09at org.apache.solr.client.solrj.request.QueryRequest.process(QueryReques= t.java:90) =09at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsist= ency(AbstractFullDistribZkTestBase.java:909) =09at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:226) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:695) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:616) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at java.lang.Thread.run(Thread.java:679) Build Log: [...truncated 7047 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.SyncSliceTest [junit4:junit4] 2> 11 T58 oas.SolrTestCaseJ4.setUp ###Starting testDistri= bSearch [junit4:junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/worksp= ace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-S= yncSliceTest-1347772207408 [junit4:junit4] 2> 14 T58 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 22 T59 oazs.ZooKeeperServer.setTickTime tickTime set t= o 1000 [junit4:junit4] 2> 23 T59 oazs.NIOServerCnxn$Factory. binding to po= rt 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 25 T59 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 116 T58 oasc.ZkTestServer.run start zk server on port:= 27791 [junit4:junit4] 2> 117 T58 oaz.ZooKeeper. Initiating client connect= ion, connectString=3D127.0.0.1:27791 sessionTimeout=3D10000 watcher=3Dorg.a= pache.solr.common.cloud.ConnectionManager@593887c2 [junit4:junit4] 2> 119 T58 oascc.ConnectionManager.waitForConnected Waiti= ng for client to connect to ZooKeeper [junit4:junit4] 2> 119 T64 oaz.ClientCnxn$SendThread.startConnect Opening= socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 121 T64 oaz.ClientCnxn$SendThread.primeConnection Sock= et connection established to localhost/127.0.0.1:27791, initiating session [junit4:junit4] 2> 122 T60 oazs.NIOServerCnxn$Factory.run Accepted socket= connection from /140.211.11.196:13962 [junit4:junit4] 2> 123 T60 oazs.NIOServerCnxn.readConnectRequest Client a= ttempting to establish new session at /140.211.11.196:13962 [junit4:junit4] 2> 124 T62 oazsp.FileTxnLog.append Creating new log file:= log.1 [junit4:junit4] 2> 133 T62 oazs.NIOServerCnxn.finishSessionInit Establish= ed session 0x139cd7bd13e0000 with negotiated timeout 10000 for client /140.= 211.11.196:13962 [junit4:junit4] 2> 133 T64 oaz.ClientCnxn$SendThread.readConnectResult Se= ssion establishment complete on server localhost/127.0.0.1:27791, sessionid= =3D 0x139cd7bd13e0000, negotiated timeout =3D 10000 [junit4:junit4] 2> 134 T65 oascc.ConnectionManager.process Watcher org.ap= ache.solr.common.cloud.ConnectionManager@593887c2 name:ZooKeeperConnection = Watcher:127.0.0.1:27791 got event WatchedEvent state:SyncConnected type:Non= e path:null path:null type:None [junit4:junit4] 2> 137 T58 oascc.ConnectionManager.waitForConnected Clien= t is connected to ZooKeeper [junit4:junit4] 2> 138 T58 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 148 T63 oazs.PrepRequestProcessor.pRequest Processed s= ession termination for sessionid: 0x139cd7bd13e0000 [junit4:junit4] 2> 152 T65 oaz.ClientCnxn$EventThread.run EventThread shu= t down [junit4:junit4] 2> 152 T58 oaz.ZooKeeper.close Session: 0x139cd7bd13e0000= closed [junit4:junit4] 2> 154 T58 oaz.ZooKeeper. Initiating client connect= ion, connectString=3D127.0.0.1:27791/solr sessionTimeout=3D10000 watcher=3D= org.apache.solr.common.cloud.ConnectionManager@1ea4daa3 [junit4:junit4] 2> 155 T60 oazs.NIOServerCnxn.closeSock Closed socket con= nection for client /140.211.11.196:13962 which had sessionid 0x139cd7bd13e0= 000 [junit4:junit4] 2> 156 T66 oaz.ClientCnxn$SendThread.startConnect Opening= socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 156 T58 oascc.ConnectionManager.waitForConnected Waiti= ng for client to connect to ZooKeeper [junit4:junit4] 2> 157 T66 oaz.ClientCnxn$SendThread.primeConnection Sock= et connection established to localhost/127.0.0.1:27791, initiating session [junit4:junit4] 2> 157 T60 oazs.NIOServerCnxn$Factory.run Accepted socket= connection from /140.211.11.196:20102 [junit4:junit4] 2> 158 T60 oazs.NIOServerCnxn.readConnectRequest Client a= ttempting to establish new session at /140.211.11.196:20102 [junit4:junit4] 2> 177 T62 oazs.NIOServerCnxn.finishSessionInit Establish= ed session 0x139cd7bd13e0001 with negotiated timeout 10000 for client /140.= 211.11.196:20102 [junit4:junit4] 2> 177 T66 oaz.ClientCnxn$SendThread.readConnectResult Se= ssion establishment complete on server localhost/127.0.0.1:27791, sessionid= =3D 0x139cd7bd13e0001, negotiated timeout =3D 10000 [junit4:junit4] 2> 178 T67 oascc.ConnectionManager.process Watcher org.ap= ache.solr.common.cloud.ConnectionManager@1ea4daa3 name:ZooKeeperConnection = Watcher:127.0.0.1:27791/solr got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 179 T58 oascc.ConnectionManager.waitForConnected Clien= t is connected to ZooKeeper [junit4:junit4] 2> 180 T58 oascc.SolrZkClient.makePath makePath: /collect= ions/collection1 [junit4:junit4] 2> 193 T58 oascc.SolrZkClient.makePath makePath: /collect= ions/collection1/shards [junit4:junit4] 2> 199 T58 oascc.SolrZkClient.makePath makePath: /collect= ions/control_collection [junit4:junit4] 2> 204 T58 oascc.SolrZkClient.makePath makePath: /collect= ions/control_collection/shards [junit4:junit4] 2> 214 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/s= olrconfig.xml [junit4:junit4] 2> 217 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/solrconfig.xml [junit4:junit4] 2> 234 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schem= a.xml [junit4:junit4] 2> 235 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/schema.xml [junit4:junit4] 2> 343 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/s= olrconfig.xml [junit4:junit4] 2> 345 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/solrconfig.xml [junit4:junit4] 2> 350 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/st= opwords.txt [junit4:junit4] 2> 351 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/stopwords.txt [junit4:junit4] 2> 363 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/pr= otwords.txt [junit4:junit4] 2> 364 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/protwords.txt [junit4:junit4] 2> 375 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/cur= rency.xml [junit4:junit4] 2> 377 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/currency.xml [junit4:junit4] 2> 388 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 390 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/open-exchange-rates.json [junit4:junit4] 2> 396 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /co= nfigs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 398 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 404 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1= /old_synonyms.txt [junit4:junit4] 2> 405 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/old_synonyms.txt [junit4:junit4] 2> 414 T58 oasc.AbstractZkTestCase.putConfig put /usr/hom= e/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/syn= onyms.txt [junit4:junit4] 2> 416 T58 oascc.SolrZkClient.makePath makePath: /configs= /conf1/synonyms.txt [junit4:junit4] 2> 428 T63 oazs.PrepRequestProcessor.pRequest Processed s= ession termination for sessionid: 0x139cd7bd13e0001 [junit4:junit4] 2> 432 T67 oaz.ClientCnxn$EventThread.run EventThread shu= t down [junit4:junit4] 2> 432 T60 oazs.NIOServerCnxn.closeSock Closed socket con= nection for client /140.211.11.196:20102 which had sessionid 0x139cd7bd13e0= 001 [junit4:junit4] 2> 432 T58 oaz.ZooKeeper.close Session: 0x139cd7bd13e0001= closed [junit4:junit4] 2> 1549 T58 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 2283 T58 oejs.AbstractConnector.doStart Started Socket= Connector@0.0.0.0:34961 [junit4:junit4] 2> 2408 T58 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 2409 T58 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 2410 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 2508 T58 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() [junit4:junit4] 2> 2509 T58 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 2510 T58 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 2510 T58 oasc.CoreContainer$Initializer.initialize loo= king for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Test= s-trunk-Java6/solr/build/solr-core/test-files/solr/solr.xml [junit4:junit4] 2> 2511 T58 oasc.CoreContainer. New CoreContainer 7= 74493453 [junit4:junit4] 2> 2513 T58 oasc.CoreContainer.load Loading CoreContainer= using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Test= s-trunk-Java6/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 2513 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-trunk-Java6/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 2562 T58 oasc.CoreContainer.load Registering Log Liste= ner [junit4:junit4] 2> 2614 T58 oasc.CoreContainer.initZooKeeper Zookeeper cl= ient=3D127.0.0.1:27791/solr [junit4:junit4] 2> 2615 T58 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:27791/solr sessionTimeout=3D5000 watcher=3D= org.apache.solr.common.cloud.ConnectionManager@2c9fa2fb [junit4:junit4] 2> 2618 T78 oaz.ClientCnxn$SendThread.startConnect Openin= g socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 2618 T58 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 2636 T78 oaz.ClientCnxn$SendThread.primeConnection Soc= ket connection established to localhost/127.0.0.1:27791, initiating session [junit4:junit4] 2> 2636 T60 oazs.NIOServerCnxn$Factory.run Accepted socke= t connection from /140.211.11.196:19943 [junit4:junit4] 2> 2646 T60 oazs.NIOServerCnxn.readConnectRequest Client = attempting to establish new session at /140.211.11.196:19943 [junit4:junit4] 2> 2650 T62 oazs.NIOServerCnxn.finishSessionInit Establis= hed session 0x139cd7bd13e0002 with negotiated timeout 5000 for client /140.= 211.11.196:19943 [junit4:junit4] 2> 2663 T78 oaz.ClientCnxn$SendThread.readConnectResult S= ession establishment complete on server localhost/127.0.0.1:27791, sessioni= d =3D 0x139cd7bd13e0002, negotiated timeout =3D 5000 [junit4:junit4] 2> 2663 T79 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@2c9fa2fb name:ZooKeeperConnection= Watcher:127.0.0.1:27791/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 2664 T58 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 2668 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/= solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2672 T58 oascc.SolrZkClient.makePath makePath: /live_n= odes [junit4:junit4] 2> 2678 T58 oasc.ZkController.createEphemeralLiveNode Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:34961_solr [junit4:junit4] 2> 2680 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:delet= e cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/= solr/live_nodes/127.0.0.1:34961_solr Error:KeeperErrorCode =3D NoNode for /= solr/live_nodes/127.0.0.1:34961_solr [junit4:junit4] 2> 2682 T58 oascc.SolrZkClient.makePath makePath: /live_n= odes/127.0.0.1:34961_solr [junit4:junit4] 2> 2688 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 0 [junit4:junit4] 2> 2689 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to: http:// [junit4:junit4] 2> 2690 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 0 [junit4:junit4] 2> 2691 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 2692 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4:junit4] 2> 2692 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 2693 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4:junit4] 2> 2694 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4:junit4] 2> 2695 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4:junit4] 2> 2695 T58 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&so= cketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 2744 T58 oascc.SolrZkClient.makePath makePath: /overse= er_elect/election [junit4:junit4] 2> 2772 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:delet= e cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/over= seer_elect/leader [junit4:junit4] 2> 2786 T58 oascc.SolrZkClient.makePath makePath: /overse= er_elect/leader [junit4:junit4] 2> 2791 T58 oasc.Overseer.start Overseer (id=3D8832759938= 5608194-127.0.0.1:34961_solr-n_0000000000) starting [junit4:junit4] 2> 2792 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2797 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2799 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2801 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2811 T81 oasc.OverseerCollectionProcessor.run Process = current queue of collection creations [junit4:junit4] 2> 2813 T58 oascc.SolrZkClient.makePath makePath: /cluste= rstate.json [junit4:junit4] 2> 2838 T58 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 2844 T80 oasc.Overseer$ClusterStateUpdater.run Startin= g to work on the main queue [junit4:junit4] 2> 2876 T58 oasc.CoreContainer.create Creating SolrCore '= collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test-files/solr/collection1 [junit4:junit4] 2> 2877 T58 oasc.ZkController.createCollectionZkNode Chec= k for collection zkNode:control_collection [junit4:junit4] 2> 2879 T58 oasc.ZkController.createCollectionZkNode Coll= ection zkNode exists [junit4:junit4] 2> 2880 T58 oasc.ZkController.readConfigName Load collect= ion config from:/collections/control_collection [junit4:junit4] 2> 3135 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-trunk-Java6/solr/build/solr-core/test-files/solr/collection1/' [junit4:junit4] 2> 3137 T58 oasc.SolrResourceLoader.replaceClassLoader Ad= ding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-= Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to class= loader [junit4:junit4] 2> 3139 T58 oasc.SolrResourceLoader.replaceClassLoader Ad= ding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-= Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to cla= ssloader [junit4:junit4] 2> 3240 T58 oasc.SolrConfig. Using Lucene MatchVers= ion: LUCENE_50 [junit4:junit4] 2> 3329 T58 oasc.SolrConfig. Loaded SolrConfig: sol= rconfig.xml [junit4:junit4] 2> 3347 T58 oass.IndexSchema.readSchema Reading Solr Sche= ma [junit4:junit4] 2> 3359 T58 oass.IndexSchema.readSchema Schema name=3Dtes= t [junit4:junit4] 2> 4195 T58 oass.OpenExchangeRatesOrgProvider.init Initia= lized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 4204 T58 oass.IndexSchema.readSchema default search fi= eld in schema is text [junit4:junit4] 2> 4212 T58 oass.IndexSchema.readSchema unique key field:= id [junit4:junit4] 2> 4232 T58 oass.FileExchangeRateProvider.reload Reloadin= g exchange rates from file currency.xml [junit4:junit4] 2> 4237 T58 oass.FileExchangeRateProvider.reload Reloadin= g exchange rates from file currency.xml [junit4:junit4] 2> 4242 T58 oass.OpenExchangeRatesOrgProvider.reload Relo= ading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4244 T58 oass.OpenExchangeRatesOrgProvider.reload Relo= ading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4246 T58 oasc.SolrCore. [collection1] Opening ne= w SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=3D./org= .apache.solr.cloud.SyncSliceTest-1347772207407/control/data/ [junit4:junit4] 2> 4246 T58 oasc.JmxMonitoredMap. JMX monitoring is= enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBea= nServer@5f0f0625 [junit4:junit4] 2> 4273 T58 oasc.SolrCore.getNewIndexDir WARNING New inde= x directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTes= t-1347772207407/control/data/index/ [junit4:junit4] 2> 4274 T58 oasc.SolrCore.initIndex WARNING [collection1]= Solr index directory './org.apache.solr.cloud.SyncSliceTest-1347772207407/= control/data/index' doesn't exist. Creating new index... [junit4:junit4] 2> 4275 T58 oasc.CachingDirectoryFactory.get return new d= irectory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trun= k-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-= 1347772207407/control/data/index forceNew:false [junit4:junit4] 2> 4294 T58 oasc.SolrDeletionPolicy.onCommit SolrDeletion= Policy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/control/data/index lockFactory=3Dorg.apache.lucene.s= tore.NativeFSLockFactory@37f2ae62; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),= segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 4295 T58 oasc.SolrDeletionPolicy.updateCommits newest = commit =3D 1 [junit4:junit4] 2> 4296 T58 oasc.SolrCore.initWriters created xml: solr.X= MLResponseWriter [junit4:junit4] 2> 4298 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "ded= upe" [junit4:junit4] 2> 4298 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "ded= upe-allfields" [junit4:junit4] 2> 4299 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "sto= red_sig" [junit4:junit4] 2> 4299 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "uni= q-fields" [junit4:junit4] 2> 4300 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "dis= trib-dup-test-chain-implicit" [junit4:junit4] 2> 4301 T58 oasc.RequestHandlers.initHandlersFromConfig a= dding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 4302 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /replication: solr.ReplicationHandler [junit4:junit4] 2> 4303 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated standard: solr.StandardRequestHandler [junit4:junit4] 2> 4304 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /get: solr.RealTimeGetHandler [junit4:junit4] 2> 4304 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated dismax: solr.SearchHandler [junit4:junit4] 2> 4305 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 4306 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 4307 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated defaults: solr.StandardRequestHandler [junit4:junit4] 2> 4308 T58 oasc.RequestHandlers.initHandlersFromConfig a= dding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 4308 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated lazy: solr.StandardRequestHandler [junit4:junit4] 2> 4309 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /update: solr.UpdateRequestHandler [junit4:junit4] 2> 4310 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4311 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4312 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHan= dler [junit4:junit4] 2> 4313 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHan= dler [junit4:junit4] 2> 4314 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.Se= archHandler [junit4:junit4] 2> 4314 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4315 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4316 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 4317 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 4322 T58 oashl.XMLLoader.init xsltCacheLifetimeSeconds= =3D60 [junit4:junit4] 2> 4328 T58 oasc.SolrCore.initDeprecatedSupport WARNING s= olrconfig.xml uses deprecated , Please update your con= fig to use the ShowFileRequestHandler. [junit4:junit4] 2> 4330 T58 oasc.SolrCore.initDeprecatedSupport WARNING a= dding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.T= XT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, C= URRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 4334 T58 oass.SolrIndexSearcher. Opening Searche= r@7910769b main [junit4:junit4] 2> 4355 T58 oasu.CommitTracker. Hard AutoCommit: di= sabled [junit4:junit4] 2> 4355 T58 oasu.CommitTracker. Soft AutoCommit: di= sabled [junit4:junit4] 2> 4356 T58 oashc.SpellCheckComponent.inform Initializing= spell checkers [junit4:junit4] 2> 4375 T58 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 4435 T82 oasc.SolrCore.registerSearcher [collection1] = Registered new searcher Searcher@7910769b main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 4439 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x59 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer/queue Error:KeeperErrorCode =3D NoNode for /solr/overseer/qu= eue [junit4:junit4] 2> 4452 T58 oasc.CoreContainer.register registering core:= collection1 [junit4:junit4] 2> 4452 T58 oasc.ZkController.register Register shard - c= ore:collection1 address:http://127.0.0.1:34961/solr shardId:control_shard [junit4:junit4] 2> 4453 T58 oascc.SolrZkClient.makePath makePath: /collec= tions/control_collection/leader_elect/control_shard/election [junit4:junit4] 2> 4474 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:delet= e cxid:0x68 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/collections/control_collection/leaders Error:KeeperErrorCode =3D NoNo= de for /solr/collections/control_collection/leaders [junit4:junit4] 2> 4475 T58 oasc.ShardLeaderElectionContext.runLeaderProc= ess Running the leader process. [junit4:junit4] 2> 4477 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x69 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4857 T80 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4858 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x70 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer/queue-work Error:KeeperErrorCode =3D NoNode for /solr/overse= er/queue-work [junit4:junit4] 2> 5117 T79 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 5492 T58 oasc.ShardLeaderElectionContext.waitForReplic= asToComeUp Enough replicas found to continue. [junit4:junit4] 2> 5492 T58 oasc.ShardLeaderElectionContext.runLeaderProc= ess I may be the new leader - try and sync [junit4:junit4] 2> 5528 T58 oasc.SyncStrategy.sync Sync replicas to http:= //127.0.0.1:34961/solr/collection1/ [junit4:junit4] 2> 5529 T58 oasc.SyncStrategy.syncReplicas Sync Success -= now sync replicas to me [junit4:junit4] 2> 5530 T58 oasc.SyncStrategy.syncToMe http://127.0.0.1:3= 4961/solr/collection1/ has no replicas [junit4:junit4] 2> 5531 T58 oasc.ShardLeaderElectionContext.runLeaderProc= ess I am the new leader: http://127.0.0.1:34961/solr/collection1/ [junit4:junit4] 2> 5531 T58 oascc.SolrZkClient.makePath makePath: /collec= tions/control_collection/leaders/control_shard [junit4:junit4] 2> 5549 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0002 type:creat= e cxid:0x8e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 5688 T80 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5704 T79 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 5711 T58 oasc.ZkController.register We are http://127.= 0.0.1:34961/solr/collection1/ and leader is http://127.0.0.1:34961/solr/col= lection1/ [junit4:junit4] 2> 5716 T58 oasc.ZkController.register No LogReplay neede= d for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:34961/solr [junit4:junit4] 2> 5717 T58 oasc.ZkController.checkRecovery I am the lead= er, no recovery necessary [junit4:junit4] 2> 5720 T58 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5721 T58 oass.SolrDispatchFilter.init user.dir=3D/usr/= home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build= /solr-core/test/J1 [junit4:junit4] 2> 5722 T58 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() done [junit4:junit4] 2> 5800 T58 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&foll= owRedirects=3Dfalse [junit4:junit4] 2> 5988 T58 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 5991 T58 oejs.AbstractConnector.doStart Started Socket= Connector@0.0.0.0:36944 [junit4:junit4] 2> 5992 T58 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 5992 T58 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1= 347772213221 [junit4:junit4] 2> 5992 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-= 1347772213221/' [junit4:junit4] 2> 6031 T58 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() [junit4:junit4] 2> 6031 T58 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 6032 T58 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1= 347772213221 [junit4:junit4] 2> 6032 T58 oasc.CoreContainer$Initializer.initialize loo= king for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Test= s-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1347772213221/= solr.xml [junit4:junit4] 2> 6032 T58 oasc.CoreContainer. New CoreContainer 2= 97690383 [junit4:junit4] 2> 6033 T58 oasc.CoreContainer.load Loading CoreContainer= using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Test= s-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1347772213221/= ' [junit4:junit4] 2> 6033 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-13477722= 13221/' [junit4:junit4] 2> 6056 T58 oasc.CoreContainer.load Registering Log Liste= ner [junit4:junit4] 2> 6068 T58 oasc.CoreContainer.initZooKeeper Zookeeper cl= ient=3D127.0.0.1:27791/solr [junit4:junit4] 2> 6068 T58 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:27791/solr sessionTimeout=3D5000 watcher=3D= org.apache.solr.common.cloud.ConnectionManager@4402083d [junit4:junit4] 2> 6069 T92 oaz.ClientCnxn$SendThread.startConnect Openin= g socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 6069 T58 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 6071 T92 oaz.ClientCnxn$SendThread.primeConnection Soc= ket connection established to localhost/127.0.0.1:27791, initiating session [junit4:junit4] 2> 6071 T60 oazs.NIOServerCnxn$Factory.run Accepted socke= t connection from /140.211.11.196:61569 [junit4:junit4] 2> 6072 T60 oazs.NIOServerCnxn.readConnectRequest Client = attempting to establish new session at /140.211.11.196:61569 [junit4:junit4] 2> 6076 T62 oazs.NIOServerCnxn.finishSessionInit Establis= hed session 0x139cd7bd13e0003 with negotiated timeout 5000 for client /140.= 211.11.196:61569 [junit4:junit4] 2> 6076 T92 oaz.ClientCnxn$SendThread.readConnectResult S= ession establishment complete on server localhost/127.0.0.1:27791, sessioni= d =3D 0x139cd7bd13e0003, negotiated timeout =3D 5000 [junit4:junit4] 2> 6077 T93 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@4402083d name:ZooKeeperConnection= Watcher:127.0.0.1:27791/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 6077 T58 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 6078 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0003 type:creat= e cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/= solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6085 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0003 type:creat= e cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/= solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6087 T58 oasc.ZkController.createEphemeralLiveNode Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:36944_solr [junit4:junit4] 2> 6088 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0003 type:delet= e cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/= solr/live_nodes/127.0.0.1:36944_solr Error:KeeperErrorCode =3D NoNode for /= solr/live_nodes/127.0.0.1:36944_solr [junit4:junit4] 2> 6089 T58 oascc.SolrZkClient.makePath makePath: /live_n= odes/127.0.0.1:36944_solr [junit4:junit4] 2> 6098 T79 oascc.ZkStateReader$3.process Updating live n= odes [junit4:junit4] 2> 6099 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 0 [junit4:junit4] 2> 6100 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to: http:// [junit4:junit4] 2> 6100 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 0 [junit4:junit4] 2> 6101 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 6101 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4:junit4] 2> 6102 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 6102 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4:junit4] 2> 6103 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4:junit4] 2> 6103 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4:junit4] 2> 6104 T58 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&so= cketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 6124 T58 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 6131 T58 oasc.CoreContainer.create Creating SolrCore '= collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1= 347772213221/collection1 [junit4:junit4] 2> 6132 T58 oasc.ZkController.createCollectionZkNode Chec= k for collection zkNode:collection1 [junit4:junit4] 2> 6133 T58 oasc.ZkController.createCollectionZkNode Coll= ection zkNode exists [junit4:junit4] 2> 6133 T58 oasc.ZkController.readConfigName Load collect= ion config from:/collections/collection1 [junit4:junit4] 2> 6135 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-13477722= 13221/collection1/' [junit4:junit4] 2> 6136 T58 oasc.SolrResourceLoader.replaceClassLoader Ad= ding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-= Java6/solr/build/solr-core/test/J1/solrtest-jetty1-1347772213221/collection= 1/lib/classes/' to classloader [junit4:junit4] 2> 6137 T58 oasc.SolrResourceLoader.replaceClassLoader Ad= ding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-= Java6/solr/build/solr-core/test/J1/solrtest-jetty1-1347772213221/collection= 1/lib/README' to classloader [junit4:junit4] 2> 6213 T80 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6213 T58 oasc.SolrConfig. Using Lucene MatchVers= ion: LUCENE_50 [junit4:junit4] 2> 6229 T93 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 6229 T79 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 6285 T58 oasc.SolrConfig. Loaded SolrConfig: sol= rconfig.xml [junit4:junit4] 2> 6386 T58 oass.IndexSchema.readSchema Reading Solr Sche= ma [junit4:junit4] 2> 6395 T58 oass.IndexSchema.readSchema Schema name=3Dtes= t [junit4:junit4] 2> 7097 T58 oass.OpenExchangeRatesOrgProvider.init Initia= lized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 7107 T58 oass.IndexSchema.readSchema default search fi= eld in schema is text [junit4:junit4] 2> 7111 T58 oass.IndexSchema.readSchema unique key field:= id [junit4:junit4] 2> 7131 T58 oass.FileExchangeRateProvider.reload Reloadin= g exchange rates from file currency.xml [junit4:junit4] 2> 7136 T58 oass.FileExchangeRateProvider.reload Reloadin= g exchange rates from file currency.xml [junit4:junit4] 2> 7141 T58 oass.OpenExchangeRatesOrgProvider.reload Relo= ading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 7143 T58 oass.OpenExchangeRatesOrgProvider.reload Relo= ading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 7145 T58 oasc.SolrCore. [collection1] Opening ne= w SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1347772213221/colle= ction1/, dataDir=3D./org.apache.solr.cloud.SyncSliceTest-1347772207407/jett= y1/ [junit4:junit4] 2> 7145 T58 oasc.JmxMonitoredMap. JMX monitoring is= enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBea= nServer@5f0f0625 [junit4:junit4] 2> 7147 T58 oasc.SolrCore.getNewIndexDir WARNING New inde= x directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTes= t-1347772207407/jetty1/index/ [junit4:junit4] 2> 7147 T58 oasc.SolrCore.initIndex WARNING [collection1]= Solr index directory './org.apache.solr.cloud.SyncSliceTest-1347772207407/= jetty1/index' doesn't exist. Creating new index... [junit4:junit4] 2> 7148 T58 oasc.CachingDirectoryFactory.get return new d= irectory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trun= k-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-= 1347772207407/jetty1/index forceNew:false [junit4:junit4] 2> 7155 T58 oasc.SolrDeletionPolicy.onCommit SolrDeletion= Policy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty1/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@c39a867; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 7155 T58 oasc.SolrDeletionPolicy.updateCommits newest = commit =3D 1 [junit4:junit4] 2> 7156 T58 oasc.SolrCore.initWriters created xml: solr.X= MLResponseWriter [junit4:junit4] 2> 7158 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "ded= upe" [junit4:junit4] 2> 7159 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "ded= upe-allfields" [junit4:junit4] 2> 7159 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "sto= red_sig" [junit4:junit4] 2> 7160 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "uni= q-fields" [junit4:junit4] 2> 7160 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "dis= trib-dup-test-chain-implicit" [junit4:junit4] 2> 7161 T58 oasc.RequestHandlers.initHandlersFromConfig a= dding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 7161 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /replication: solr.ReplicationHandler [junit4:junit4] 2> 7162 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated standard: solr.StandardRequestHandler [junit4:junit4] 2> 7162 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /get: solr.RealTimeGetHandler [junit4:junit4] 2> 7163 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated dismax: solr.SearchHandler [junit4:junit4] 2> 7164 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 7164 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 7165 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated defaults: solr.StandardRequestHandler [junit4:junit4] 2> 7165 T58 oasc.RequestHandlers.initHandlersFromConfig a= dding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 7166 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated lazy: solr.StandardRequestHandler [junit4:junit4] 2> 7166 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /update: solr.UpdateRequestHandler [junit4:junit4] 2> 7167 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7168 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7168 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHan= dler [junit4:junit4] 2> 7169 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHan= dler [junit4:junit4] 2> 7169 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.Se= archHandler [junit4:junit4] 2> 7170 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7170 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7171 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 7172 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 7176 T58 oashl.XMLLoader.init xsltCacheLifetimeSeconds= =3D60 [junit4:junit4] 2> 7181 T58 oasc.SolrCore.initDeprecatedSupport WARNING s= olrconfig.xml uses deprecated , Please update your con= fig to use the ShowFileRequestHandler. [junit4:junit4] 2> 7182 T58 oasc.SolrCore.initDeprecatedSupport WARNING a= dding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.T= XT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, C= URRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 7186 T58 oass.SolrIndexSearcher. Opening Searche= r@419829a9 main [junit4:junit4] 2> 7187 T58 oasu.CommitTracker. Hard AutoCommit: di= sabled [junit4:junit4] 2> 7187 T58 oasu.CommitTracker. Soft AutoCommit: di= sabled [junit4:junit4] 2> 7188 T58 oashc.SpellCheckComponent.inform Initializing= spell checkers [junit4:junit4] 2> 7209 T58 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 7292 T94 oasc.SolrCore.registerSearcher [collection1] = Registered new searcher Searcher@419829a9 main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 7740 T80 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7782 T79 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 7782 T93 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 7799 T58 oasc.CoreContainer.register registering core:= collection1 [junit4:junit4] 2> 7799 T58 oasc.ZkController.register Register shard - c= ore:collection1 address:http://127.0.0.1:36944/solr shardId:shard1 [junit4:junit4] 2> 7800 T58 oascc.SolrZkClient.makePath makePath: /collec= tions/collection1/leader_elect/shard1/election [junit4:junit4] 2> 7816 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0003 type:delet= e cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/collections/collection1/leaders Error:KeeperErrorCode =3D NoNode for = /solr/collections/collection1/leaders [junit4:junit4] 2> 7818 T58 oasc.ShardLeaderElectionContext.runLeaderProc= ess Running the leader process. [junit4:junit4] 2> 7819 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0003 type:creat= e cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 7828 T58 oasc.ShardLeaderElectionContext.waitForReplic= asToComeUp Enough replicas found to continue. [junit4:junit4] 2> 7828 T58 oasc.ShardLeaderElectionContext.runLeaderProc= ess I may be the new leader - try and sync [junit4:junit4] 2> 7829 T58 oasc.SyncStrategy.sync Sync replicas to http:= //127.0.0.1:36944/solr/collection1/ [junit4:junit4] 2> 7829 T58 oasc.SyncStrategy.syncReplicas Sync Success -= now sync replicas to me [junit4:junit4] 2> 7829 T58 oasc.SyncStrategy.syncToMe http://127.0.0.1:3= 6944/solr/collection1/ has no replicas [junit4:junit4] 2> 7830 T58 oasc.ShardLeaderElectionContext.runLeaderProc= ess I am the new leader: http://127.0.0.1:36944/solr/collection1/ [junit4:junit4] 2> 7830 T58 oascc.SolrZkClient.makePath makePath: /collec= tions/collection1/leaders/shard1 [junit4:junit4] 2> 7835 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0003 type:creat= e cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8293 T80 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 8316 T79 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 8316 T93 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 8356 T58 oasc.ZkController.register We are http://127.= 0.0.1:36944/solr/collection1/ and leader is http://127.0.0.1:36944/solr/col= lection1/ [junit4:junit4] 2> 8356 T58 oasc.ZkController.register No LogReplay neede= d for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:36944/solr [junit4:junit4] 2> 8357 T58 oasc.ZkController.checkRecovery I am the lead= er, no recovery necessary [junit4:junit4] 2> 8360 T58 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 8361 T58 oass.SolrDispatchFilter.init user.dir=3D/usr/= home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/build= /solr-core/test/J1 [junit4:junit4] 2> 8362 T58 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() done [junit4:junit4] 2> 8362 T58 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&foll= owRedirects=3Dfalse [junit4:junit4] 2> 8556 T58 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 8560 T58 oejs.AbstractConnector.doStart Started Socket= Connector@0.0.0.0:10606 [junit4:junit4] 2> 8560 T58 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 8561 T58 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1= 347772215770 [junit4:junit4] 2> 8562 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-= 1347772215770/' [junit4:junit4] 2> 8602 T58 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() [junit4:junit4] 2> 8603 T58 oasc.SolrResourceLoader.locateSolrHome JNDI n= ot configured for solr (NoInitialContextEx) [junit4:junit4] 2> 8604 T58 oasc.SolrResourceLoader.locateSolrHome using = system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1= 347772215770 [junit4:junit4] 2> 8604 T58 oasc.CoreContainer$Initializer.initialize loo= king for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Test= s-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1347772215770/= solr.xml [junit4:junit4] 2> 8605 T58 oasc.CoreContainer. New CoreContainer 1= 68610966 [junit4:junit4] 2> 8606 T58 oasc.CoreContainer.load Loading CoreContainer= using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Test= s-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1347772215770/= ' [junit4:junit4] 2> 8606 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-13477722= 15770/' [junit4:junit4] 2> 8635 T58 oasc.CoreContainer.load Registering Log Liste= ner [junit4:junit4] 2> 8653 T58 oasc.CoreContainer.initZooKeeper Zookeeper cl= ient=3D127.0.0.1:27791/solr [junit4:junit4] 2> 8654 T58 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:27791/solr sessionTimeout=3D5000 watcher=3D= org.apache.solr.common.cloud.ConnectionManager@431d00cf [junit4:junit4] 2> 8655 T104 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 8655 T58 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 8656 T104 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:27791, initiating sessio= n [junit4:junit4] 2> 8656 T60 oazs.NIOServerCnxn$Factory.run Accepted socke= t connection from /140.211.11.196:63898 [junit4:junit4] 2> 8657 T60 oazs.NIOServerCnxn.readConnectRequest Client = attempting to establish new session at /140.211.11.196:63898 [junit4:junit4] 2> 8659 T62 oazs.NIOServerCnxn.finishSessionInit Establis= hed session 0x139cd7bd13e0004 with negotiated timeout 5000 for client /140.= 211.11.196:63898 [junit4:junit4] 2> 8659 T104 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:27791, session= id =3D 0x139cd7bd13e0004, negotiated timeout =3D 5000 [junit4:junit4] 2> 8659 T105 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@431d00cf name:ZooKeeperConnectio= n Watcher:127.0.0.1:27791/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 8660 T58 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 8661 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0004 type:creat= e cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/= solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8669 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0004 type:creat= e cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/= solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8671 T58 oasc.ZkController.createEphemeralLiveNode Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:10606_solr [junit4:junit4] 2> 8672 T63 oazs.PrepRequestProcessor.pRequest Got user-l= evel KeeperException when processing sessionid:0x139cd7bd13e0004 type:delet= e cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/= solr/live_nodes/127.0.0.1:10606_solr Error:KeeperErrorCode =3D NoNode for /= solr/live_nodes/127.0.0.1:10606_solr [junit4:junit4] 2> 8673 T58 oascc.SolrZkClient.makePath makePath: /live_n= odes/127.0.0.1:10606_solr [junit4:junit4] 2> 8681 T79 oascc.ZkStateReader$3.process Updating live n= odes [junit4:junit4] 2> 8681 T93 oascc.ZkStateReader$3.process Updating live n= odes [junit4:junit4] 2> 8683 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 0 [junit4:junit4] 2> 8683 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to: http:// [junit4:junit4] 2> 8684 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 0 [junit4:junit4] 2> 8684 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 8684 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4:junit4] 2> 8685 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 8685 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4:junit4] 2> 8685 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4:junit4] 2> 8686 T58 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4:junit4] 2> 8686 T58 oascsi.HttpClientUtil.createClient Creating n= ew http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&so= cketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 8702 T58 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 8708 T58 oasc.CoreContainer.create Creating SolrCore '= collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Luc= ene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1= 347772215770/collection1 [junit4:junit4] 2> 8708 T58 oasc.ZkController.createCollectionZkNode Chec= k for collection zkNode:collection1 [junit4:junit4] 2> 8709 T58 oasc.ZkController.createCollectionZkNode Coll= ection zkNode exists [junit4:junit4] 2> 8710 T58 oasc.ZkController.readConfigName Load collect= ion config from:/collections/collection1 [junit4:junit4] 2> 8711 T58 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Sol= r-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-13477722= 15770/collection1/' [junit4:junit4] 2> 8712 T58 oasc.SolrResourceLoader.replaceClassLoader Ad= ding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-= Java6/solr/build/solr-core/test/J1/solrtest-jetty2-1347772215770/collection= 1/lib/classes/' to classloader [junit4:junit4] 2> 8713 T58 oasc.SolrResourceLoader.replaceClassLoader Ad= ding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-= Java6/solr/build/solr-core/test/J1/solrtest-jetty2-1347772215770/collection= 1/lib/README' to classloader [junit4:junit4] 2> 8751 T58 oasc.SolrConfig. Using Lucene MatchVers= ion: LUCENE_50 [junit4:junit4] 2> 8804 T58 oasc.SolrConfig. Loaded SolrConfig: sol= rconfig.xml [junit4:junit4] 2> 8831 T80 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 8849 T93 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 8849 T79 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 8905 T105 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 8905 T58 oass.IndexSchema.readSchema Reading Solr Sche= ma [junit4:junit4] 2> 8911 T58 oass.IndexSchema.readSchema Schema name=3Dtes= t [junit4:junit4] 2> 9538 T58 oass.OpenExchangeRatesOrgProvider.init Initia= lized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 9548 T58 oass.IndexSchema.readSchema default search fi= eld in schema is text [junit4:junit4] 2> 9551 T58 oass.IndexSchema.readSchema unique key field:= id [junit4:junit4] 2> 9568 T58 oass.FileExchangeRateProvider.reload Reloadin= g exchange rates from file currency.xml [junit4:junit4] 2> 9573 T58 oass.FileExchangeRateProvider.reload Reloadin= g exchange rates from file currency.xml [junit4:junit4] 2> 9577 T58 oass.OpenExchangeRatesOrgProvider.reload Relo= ading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 9579 T58 oass.OpenExchangeRatesOrgProvider.reload Relo= ading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 9580 T58 oasc.SolrCore. [collection1] Opening ne= w SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1347772215770/colle= ction1/, dataDir=3D./org.apache.solr.cloud.SyncSliceTest-1347772207407/jett= y2/ [junit4:junit4] 2> 9581 T58 oasc.JmxMonitoredMap. JMX monitoring is= enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBea= nServer@5f0f0625 [junit4:junit4] 2> 9582 T58 oasc.SolrCore.getNewIndexDir WARNING New inde= x directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTes= t-1347772207407/jetty2/index/ [junit4:junit4] 2> 9583 T58 oasc.SolrCore.initIndex WARNING [collection1]= Solr index directory './org.apache.solr.cloud.SyncSliceTest-1347772207407/= jetty2/index' doesn't exist. Creating new index... [junit4:junit4] 2> 9584 T58 oasc.CachingDirectoryFactory.get return new d= irectory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trun= k-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-= 1347772207407/jetty2/index forceNew:false [junit4:junit4] 2> 9590 T58 oasc.SolrDeletionPolicy.onCommit SolrDeletion= Policy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty2/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@153bcbc8; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 9591 T58 oasc.SolrDeletionPolicy.updateCommits newest = commit =3D 1 [junit4:junit4] 2> 9592 T58 oasc.SolrCore.initWriters created xml: solr.X= MLResponseWriter [junit4:junit4] 2> 9598 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "ded= upe" [junit4:junit4] 2> 9599 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "ded= upe-allfields" [junit4:junit4] 2> 9599 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "sto= red_sig" [junit4:junit4] 2> 9600 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "uni= q-fields" [junit4:junit4] 2> 9601 T58 oasup.UpdateRequestProcessorChain.init insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "dis= trib-dup-test-chain-implicit" [junit4:junit4] 2> 9601 T58 oasc.RequestHandlers.initHandlersFromConfig a= dding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 9601 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /replication: solr.ReplicationHandler [junit4:junit4] 2> 9602 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated standard: solr.StandardRequestHandler [junit4:junit4] 2> 9603 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /get: solr.RealTimeGetHandler [junit4:junit4] 2> 9603 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated dismax: solr.SearchHandler [junit4:junit4] 2> 9604 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 9605 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 9605 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated defaults: solr.StandardRequestHandler [junit4:junit4] 2> 9605 T58 oasc.RequestHandlers.initHandlersFromConfig a= dding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 9606 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated lazy: solr.StandardRequestHandler [junit4:junit4] 2> 9607 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /update: solr.UpdateRequestHandler [junit4:junit4] 2> 9607 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9608 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9608 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHan= dler [junit4:junit4] 2> 9609 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHan= dler [junit4:junit4] 2> 9610 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.Se= archHandler [junit4:junit4] 2> 9610 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9611 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9611 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 9612 T58 oasc.RequestHandlers.initHandlersFromConfig c= reated /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 9619 T58 oashl.XMLLoader.init xsltCacheLifetimeSeconds= =3D60 [junit4:junit4] 2> 9624 T58 oasc.SolrCore.initDeprecatedSupport WARNING s= olrconfig.xml uses deprecated , Please update your con= fig to use the ShowFileRequestHandler. [junit4:junit4] 2> 9626 T58 oasc.SolrCore.initDeprecatedSupport WARNING a= dding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.T= XT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, C= URRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 9630 T58 oass.SolrIndexSearcher. Opening Searche= r@356f5b17 main [junit4:junit4] 2> 9637 T58 oasu.CommitTracker. Hard AutoCommit: di= sabled [junit4:junit4] 2> 9637 T58 oasu.CommitTracker. Soft AutoCommit: di= sabled [junit4:junit4] 2> 9638 T58 oashc.SpellCheckComponent.inform Initializing= spell checkers [junit4:junit4] 2> 9650 T58 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 9779 T106 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@356f5b17 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 9857 T80 oascc.ZkStateReader.updateClusterState Updati= ng cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9915 T93 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 9915 T79 oascc.ZkStateReader$2.process A cluster state= change has occurred - updating... [junit4:junit4] 2> 9915 T105 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 10290 T58 oasc.CoreContainer.register registering core= : collection1 [junit4:junit4] 2> 10290 T58 oasc.ZkController.register Register shard - = core:collection1 address:http://127.0.0.1:10606/solr shardId:shard1 [junit4:junit4] 2> 10294 T58 oasc.ZkController.register We are http://127= .0.0.1:10606/solr/collection1/ and leader is http://127.0.0.1:36944/solr/co= llection1/ [junit4:junit4] 2> 10295 T58 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:10606/solr [junit4:junit4] 2> 10295 T58 oasc.ZkController.checkRecovery Core needs t= o recover:collection1 [junit4:junit4] 2> 10295 T58 oasu.DefaultSolrCoreState.doRecovery Running= recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C5 name=3Dcollection1 org.apache.solr.= core.SolrCore@5c057df5 url=3Dhttp://127.0.0.1:10606/solr/collection1 node= =3D127.0.0.1:10606_solr [junit4:junit4] 2> 10303 T107 C5 P10606 oasc.RecoveryStrategy.run Startin= g recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 10303 T107 C5 P10606 oasc.RecoveryStrategy.doRecovery = ###### startupVersions=3D[] [junit4:junit4] 2> 10303 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10336 T107 C5 P10606 oasc.RecoveryStrategy.doRecovery = Attempting to PeerSync from http://127.0.0.1:36944/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 10337 T58 oass.SolrDispatchFilter.init user.dir=3D/usr= /home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 10337 T58 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 10338 T58 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 10348 T107 C5 P10606 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnectionsPerHost=3D20&maxConnection= s=3D10000&connTimeout=3D30000&socketTimeout=3D30000&retry=3Dfalse [junit4:junit4] 2> 10376 T107 C5 P10606 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:10606/solr START replicas=3D[http://1= 27.0.0.1:36944/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 10394 T107 C5 P10606 oasu.PeerSync.sync WARNING no fra= me of reference to tell of we've missed updates [junit4:junit4] 2> 10394 T107 C5 P10606 oasc.RecoveryStrategy.doRecovery = PeerSync Recovery was not successful - trying replication. core=3Dcollectio= n1 [junit4:junit4] 2> 10394 T107 C5 P10606 oasc.RecoveryStrategy.doRecovery = Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 10395 T107 C5 P10606 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnections=3D128&maxConnectionsPerHo= st=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 10426 T80 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10445 T105 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10445 T93 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 10445 T79 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 10599 T58 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 10603 T58 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:14671 [junit4:junit4] 2> 10604 T58 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 10604 T58 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-= 1347772217753 [junit4:junit4] 2> 10605 T58 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3= -1347772217753/' [junit4:junit4] 2> 10902 T58 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 10903 T58 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 10903 T58 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-= 1347772217753 [junit4:junit4] 2> 10904 T58 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1347772217753= /solr.xml [junit4:junit4] 2> 10904 T58 oasc.CoreContainer. New CoreContainer = 1896973799 [junit4:junit4] 2> 10905 T58 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1347772217753= /' [junit4:junit4] 2> 10905 T58 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1347772= 217753/' [junit4:junit4] 2> 11226 T58 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 11362 T58 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:27791/solr [junit4:junit4] 2> 11363 T58 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:27791/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@986b0ee [junit4:junit4] 2> 11364 T118 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 11364 T58 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 11365 T118 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:27791, initiating sessi= on [junit4:junit4] 2> 11365 T60 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:25562 [junit4:junit4] 2> 11365 T60 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:25562 [junit4:junit4] 2> 11367 T62 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139cd7bd13e0005 with negotiated timeout 5000 for client /140= .211.11.196:25562 [junit4:junit4] 2> 11367 T118 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:27791, sessio= nid =3D 0x139cd7bd13e0005, negotiated timeout =3D 5000 [junit4:junit4] 2> 11367 T119 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@986b0ee name:ZooKeeperConnectio= n Watcher:127.0.0.1:27791/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 11367 T58 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 11368 T63 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139cd7bd13e0005 type:crea= te cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 11369 T63 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139cd7bd13e0005 type:crea= te cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 11380 T58 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:14671_solr [junit4:junit4] 2> 11381 T63 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139cd7bd13e0005 type:dele= te cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:14671_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:14671_solr [junit4:junit4] 2> 11382 T58 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:14671_solr [junit4:junit4] 2> 11384 T105 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 11384 T93 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 11384 T79 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 11385 T58 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 11386 T58 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 11386 T58 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 11386 T58 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 11387 T58 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 11387 T58 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 11387 T58 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 11388 T58 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 11388 T58 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 11388 T58 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 11405 T58 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 11440 T58 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-= 1347772217753/collection1 [junit4:junit4] 2> 11441 T58 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4:junit4] 2> 11442 T58 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 11442 T58 oasc.ZkController.readConfigName Load collec= tion config from:/collections/collection1 [junit4:junit4] 2> 11444 T58 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1347772= 217753/collection1/' [junit4:junit4] 2> 11446 T58 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/solrtest-jetty3-1347772217753/collectio= n1/lib/classes/' to classloader [junit4:junit4] 2> 11447 T58 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/solrtest-jetty3-1347772217753/collectio= n1/lib/README' to classloader [junit4:junit4] 2> ASYNC NEW_CORE C6 name=3Dcollection1 org.apache.solr.= core.SolrCore@2ab600af url=3Dhttp://127.0.0.1:36944/solr/collection1 node= =3D127.0.0.1:36944_solr [junit4:junit4] 2> 12026 T85 C6 P36944 REQ /get {distrib=3Dfalse&qt=3D/ge= t&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D13=20 [junit4:junit4] 2> 12037 T58 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 12205 T58 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 12307 T58 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 12316 T58 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 12830 T58 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 12842 T58 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 12846 T58 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 12864 T58 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 12869 T58 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 12874 T58 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 12876 T58 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 12878 T58 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1347772217753/coll= ection1/, dataDir=3D./org.apache.solr.cloud.SyncSliceTest-1347772207407/jet= ty3/ [junit4:junit4] 2> 12878 T58 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@5f0f0625 [junit4:junit4] 2> 12879 T58 oasc.SolrCore.getNewIndexDir WARNING New ind= ex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTe= st-1347772207407/jetty3/index/ [junit4:junit4] 2> 12880 T58 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1347772207407= /jetty3/index' doesn't exist. Creating new index... [junit4:junit4] 2> 12881 T58 oasc.CachingDirectoryFactory.get return new = directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest= -1347772207407/jetty3/index forceNew:false [junit4:junit4] 2> 12891 T58 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty3/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@53e36ec6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 12892 T58 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1 [junit4:junit4] 2> 12892 T58 oasc.SolrCore.initWriters created xml: solr.= XMLResponseWriter [junit4:junit4] 2> 12895 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 12895 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe-allfields" [junit4:junit4] 2> 12896 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 12896 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "un= iq-fields" [junit4:junit4] 2> 12897 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 12897 T58 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 12898 T58 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 12898 T58 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 12899 T58 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 12899 T58 oasc.RequestHandlers.initHandlersFromConfig = created dismax: solr.SearchHandler [junit4:junit4] 2> 12900 T58 oasc.RequestHandlers.initHandlersFromConfig = created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 12901 T58 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 12901 T58 oasc.RequestHandlers.initHandlersFromConfig = created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 12901 T58 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 12902 T58 oasc.RequestHandlers.initHandlersFromConfig = created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 12903 T58 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 12903 T58 oasc.RequestHandlers.initHandlersFromConfig = created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 12904 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 12905 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 12905 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 12906 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.S= earchHandler [junit4:junit4] 2> 12907 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 12907 T58 oasc.RequestHandlers.initHandlersFromConfig = created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 12908 T58 oasc.RequestHandlers.initHandlersFromConfig = created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 12909 T58 oasc.RequestHandlers.initHandlersFromConfig = created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 12912 T58 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 12916 T58 oasc.SolrCore.initDeprecatedSupport WARNING = solrconfig.xml uses deprecated , Please update your co= nfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 12918 T58 oasc.SolrCore.initDeprecatedSupport WARNING = adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.= TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, = CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 12921 T58 oass.SolrIndexSearcher. Opening Search= er@5b34329b main [junit4:junit4] 2> 12923 T58 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 12923 T58 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 12924 T58 oashc.SpellCheckComponent.inform Initializin= g spell checkers [junit4:junit4] 2> 12930 T58 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 12985 T120 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@5b34329b main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 13461 T80 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13478 T119 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 13478 T105 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 13478 T93 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 13478 T79 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 13495 T58 oasc.CoreContainer.register registering core= : collection1 [junit4:junit4] 2> 13495 T58 oasc.ZkController.register Register shard - = core:collection1 address:http://127.0.0.1:14671/solr shardId:shard1 [junit4:junit4] 2> 13499 T58 oasc.ZkController.register We are http://127= .0.0.1:14671/solr/collection1/ and leader is http://127.0.0.1:36944/solr/co= llection1/ [junit4:junit4] 2> 13499 T58 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:14671/solr [junit4:junit4] 2> 13500 T58 oasc.ZkController.checkRecovery Core needs t= o recover:collection1 [junit4:junit4] 2> 13500 T58 oasu.DefaultSolrCoreState.doRecovery Running= recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C7 name=3Dcollection1 org.apache.solr.= core.SolrCore@1e235551 url=3Dhttp://127.0.0.1:14671/solr/collection1 node= =3D127.0.0.1:14671_solr [junit4:junit4] 2> 13501 T121 C7 P14671 oasc.RecoveryStrategy.run Startin= g recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 13502 T121 C7 P14671 oasc.RecoveryStrategy.doRecovery = ###### startupVersions=3D[] [junit4:junit4] 2> 13501 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13503 T121 C7 P14671 oasc.RecoveryStrategy.doRecovery = Attempting to PeerSync from http://127.0.0.1:36944/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 13503 T121 C7 P14671 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:14671/solr START replicas=3D[http://1= 27.0.0.1:36944/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 13503 T58 oass.SolrDispatchFilter.init user.dir=3D/usr= /home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 13504 T121 C7 P14671 oasu.PeerSync.sync WARNING no fra= me of reference to tell of we've missed updates [junit4:junit4] 2> 13504 T58 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 13505 T121 C7 P14671 oasc.RecoveryStrategy.doRecovery = PeerSync Recovery was not successful - trying replication. core=3Dcollectio= n1 [junit4:junit4] 2> 13505 T121 C7 P14671 oasc.RecoveryStrategy.doRecovery = Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 13505 T58 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 13505 T121 C7 P14671 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnections=3D128&maxConnectionsPerHo= st=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 13508 T85 C6 P36944 REQ /get {distrib=3Dfalse&qt=3D/ge= t&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13659 T58 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 13662 T58 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:12591 [junit4:junit4] 2> 13662 T58 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 13663 T58 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1347772220915 [junit4:junit4] 2> 13663 T58 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4= -1347772220915/' [junit4:junit4] 2> 13693 T58 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 13693 T58 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 13694 T58 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1347772220915 [junit4:junit4] 2> 13694 T58 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1347772220915= /solr.xml [junit4:junit4] 2> 13695 T58 oasc.CoreContainer. New CoreContainer = 587063509 [junit4:junit4] 2> 13695 T58 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1347772220915= /' [junit4:junit4] 2> 13696 T58 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1347772= 220915/' [junit4:junit4] 2> 13717 T58 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 13730 T58 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:27791/solr [junit4:junit4] 2> 13731 T58 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:27791/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@3c993730 [junit4:junit4] 2> 13732 T132 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 13732 T58 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 13733 T132 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:27791, initiating sessi= on [junit4:junit4] 2> 13733 T60 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:32289 [junit4:junit4] 2> 13734 T60 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:32289 [junit4:junit4] 2> 13736 T62 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139cd7bd13e0006 with negotiated timeout 5000 for client /140= .211.11.196:32289 [junit4:junit4] 2> 13736 T132 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:27791, sessio= nid =3D 0x139cd7bd13e0006, negotiated timeout =3D 5000 [junit4:junit4] 2> 13737 T133 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@3c993730 name:ZooKeeperConnecti= on Watcher:127.0.0.1:27791/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 13737 T58 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 13738 T63 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139cd7bd13e0006 type:crea= te cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 13749 T63 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139cd7bd13e0006 type:crea= te cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 13750 T58 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:12591_solr [junit4:junit4] 2> 13751 T63 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139cd7bd13e0006 type:dele= te cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:12591_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:12591_solr [junit4:junit4] 2> 13752 T58 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:12591_solr [junit4:junit4] 2> 13759 T119 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 13759 T79 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 13759 T105 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 13759 T93 oascc.ZkStateReader$3.process Updating live = nodes [junit4:junit4] 2> 13760 T58 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 13761 T58 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 13761 T58 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 13762 T58 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 13762 T58 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 13762 T58 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 13763 T58 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 13763 T58 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 13763 T58 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 13764 T58 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 13780 T58 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 13786 T58 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1347772220915/collection1 [junit4:junit4] 2> 13786 T58 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4:junit4] 2> 13787 T58 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 13787 T58 oasc.ZkController.readConfigName Load collec= tion config from:/collections/collection1 [junit4:junit4] 2> 13789 T58 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-trunk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1347772= 220915/collection1/' [junit4:junit4] 2> 13790 T58 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/solrtest-jetty4-1347772220915/collectio= n1/lib/README' to classloader [junit4:junit4] 2> 13791 T58 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk= -Java6/solr/build/solr-core/test/J1/solrtest-jetty4-1347772220915/collectio= n1/lib/classes/' to classloader [junit4:junit4] 2> 13836 T58 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 13892 T58 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 13984 T80 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13993 T58 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 13998 T58 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 14000 T119 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 14000 T105 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 14000 T79 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 14000 T133 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 14000 T93 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> C5_STATE=3Dcoll:collection1 core:collection1 props:{s= hard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, collec= tion=3Dcollection1, node_name=3D127.0.0.1:10606_solr, base_url=3Dhttp://127= .0.0.1:10606/solr} [junit4:junit4] 2> 14183 T107 C5 P10606 oasc.RecoveryStrategy.doRecovery = Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 14183 T107 C5 P10606 oasu.UpdateLog.bufferUpdates Star= ting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 14183 T107 C5 P10606 oasc.RecoveryStrategy.replicate A= ttempting to replicate from http://127.0.0.1:36944/solr/collection1/. core= =3Dcollection1 [junit4:junit4] 2> 14184 T107 C5 P10606 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnections=3D128&maxConnectionsPerHo= st=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 14215 T88 C6 P36944 oascsi.HttpClientUtil.createClient= Creating new http client, config:maxConnections=3D500&maxConnectionsPerHos= t=3D16 [junit4:junit4] 2> 14231 T88 C6 P36944 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 14236 T88 C6 P36944 oasc.SolrDeletionPolicy.onInit Sol= rDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty1/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@c39a867; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 14237 T88 C6 P36944 oasc.SolrDeletionPolicy.updateComm= its newest commit =3D 1 [junit4:junit4] 2> 14239 T88 C6 P36944 oass.SolrIndexSearcher. Open= ing Searcher@4f9c205b main [junit4:junit4] 2> 14239 T88 C6 P36944 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 14241 T94 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@4f9c205b main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 14242 T88 C6 P36944 /update {waitSearcher=3Dtrue&commi= t=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version=3D= 2} {commit=3D} 0 40 [junit4:junit4] 2> 14293 T107 C5 P10606 oash.ReplicationHandler.inform Co= mmits will be reserved for 10000 [junit4:junit4] 2> 14294 T107 C5 P10606 oascsi.HttpClientUtil.createClien= t Creating new http client, config:connTimeout=3D5000&socketTimeout=3D20000= &allowCompression=3Dfalse&maxConnections=3D10000&maxConnectionsPerHost=3D10= 000 [junit4:junit4] 2> 14318 T107 C5 P10606 oash.SnapPuller. No value = set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 14367 T89 C6 P36944 oash.ReplicationHandler.inform Com= mits will be reserved for 10000 [junit4:junit4] 2> 14367 T89 C6 P36944 REQ /replication {command=3Dindexv= ersion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D47=20 [junit4:junit4] 2> 14373 T107 C5 P10606 oasc.SolrDeletionPolicy.onInit So= lrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty2/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@153bcbc8; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 14373 T107 C5 P10606 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 1 [junit4:junit4] 2> 14374 T107 C5 P10606 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 14378 T107 C5 P10606 oasc.SolrDeletionPolicy.onCommit = SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty2/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@153bcbc8; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty2/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@153bcbc8; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 14379 T107 C5 P10606 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 2 [junit4:junit4] 2> 14382 T107 C5 P10606 oass.SolrIndexSearcher. Ope= ning Searcher@5b1cd8cc main [junit4:junit4] 2> 14383 T107 C5 P10606 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 14384 T106 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@5b1cd8cc main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 14385 T107 C5 P10606 oasc.RecoveryStrategy.replay No r= eplay needed. core=3Dcollection1 [junit4:junit4] 2> 14385 T107 C5 P10606 oasc.RecoveryStrategy.doRecovery = Replication Recovery was successful - registering as Active. core=3Dcollect= ion1 [junit4:junit4] 2> 14387 T107 C5 P10606 oasc.RecoveryStrategy.doRecovery = Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 14506 T80 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 14519 T105 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 14519 T119 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 14519 T93 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 14519 T133 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 14519 T79 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 14663 T58 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 14672 T58 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 14675 T58 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 14691 T58 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 14700 T58 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 14704 T58 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 14706 T58 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 14707 T58 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tr= unk-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1347772220915/coll= ection1/, dataDir=3D./org.apache.solr.cloud.SyncSliceTest-1347772207407/jet= ty4/ [junit4:junit4] 2> 14707 T58 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@5f0f0625 [junit4:junit4] 2> 14708 T58 oasc.SolrCore.getNewIndexDir WARNING New ind= ex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceTe= st-1347772207407/jetty4/index/ [junit4:junit4] 2> 14709 T58 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1347772207407= /jetty4/index' doesn't exist. Creating new index... [junit4:junit4] 2> 14710 T58 oasc.CachingDirectoryFactory.get return new = directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-tru= nk-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest= -1347772207407/jetty4/index forceNew:false [junit4:junit4] 2> 14715 T58 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty4/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@3dad8690; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 14716 T58 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1 [junit4:junit4] 2> 14717 T58 oasc.SolrCore.initWriters created xml: solr.= XMLResponseWriter [junit4:junit4] 2> 14719 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 14719 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe-allfields" [junit4:junit4] 2> 14719 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 14720 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "un= iq-fields" [junit4:junit4] 2> 14720 T58 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 14721 T58 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 14721 T58 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 14722 T58 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 14722 T58 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 14723 T58 oasc.RequestHandlers.initHandlersFromConfig = created dismax: solr.SearchHandler [junit4:junit4] 2> 14724 T58 oasc.RequestHandlers.initHandlersFromConfig = created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 14724 T58 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 14725 T58 oasc.RequestHandlers.initHandlersFromConfig = created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 14725 T58 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 14726 T58 oasc.RequestHandlers.initHandlersFromConfig = created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 14726 T58 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 14727 T58 oasc.RequestHandlers.initHandlersFromConfig = created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 14728 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 14728 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 14729 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHa= ndler [junit4:junit4] 2> 14729 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.S= earchHandler [junit4:junit4] 2> 14730 T58 oasc.RequestHandlers.initHandlersFromConfig = created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 14730 T58 oasc.RequestHandlers.initHandlersFromConfig = created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 14731 T58 oasc.RequestHandlers.initHandlersFromConfig = created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 14731 T58 oasc.RequestHandlers.initHandlersFromConfig = created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 14734 T58 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 14738 T58 oasc.SolrCore.initDeprecatedSupport WARNING = solrconfig.xml uses deprecated , Please update your co= nfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 14740 T58 oasc.SolrCore.initDeprecatedSupport WARNING = adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.= TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, = CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 14743 T58 oass.SolrIndexSearcher. Opening Search= er@674f1c67 main [junit4:junit4] 2> 14744 T58 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 14744 T58 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 14745 T58 oashc.SpellCheckComponent.inform Initializin= g spell checkers [junit4:junit4] 2> 14751 T58 oass.DirectSolrSpellChecker.init init: {name= =3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLeng= th=3D3} [junit4:junit4] 2> 14805 T134 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@674f1c67 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 15025 T80 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15101 T105 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15101 T79 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 15101 T119 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15101 T93 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 15101 T133 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15394 T58 oasc.CoreContainer.register registering core= : collection1 [junit4:junit4] 2> 15394 T58 oasc.ZkController.register Register shard - = core:collection1 address:http://127.0.0.1:12591/solr shardId:shard1 [junit4:junit4] 2> 15406 T58 oasc.ZkController.register We are http://127= .0.0.1:12591/solr/collection1/ and leader is http://127.0.0.1:36944/solr/co= llection1/ [junit4:junit4] 2> 15407 T58 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:12591/solr [junit4:junit4] 2> 15407 T58 oasc.ZkController.checkRecovery Core needs t= o recover:collection1 [junit4:junit4] 2> 15408 T58 oasu.DefaultSolrCoreState.doRecovery Running= recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C8 name=3Dcollection1 org.apache.solr.= core.SolrCore@7eab48a7 url=3Dhttp://127.0.0.1:12591/solr/collection1 node= =3D127.0.0.1:12591_solr [junit4:junit4] 2> 15409 T135 C8 P12591 oasc.RecoveryStrategy.run Startin= g recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 15409 T135 C8 P12591 oasc.RecoveryStrategy.doRecovery = ###### startupVersions=3D[] [junit4:junit4] 2> 15409 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15410 T135 C8 P12591 oasc.RecoveryStrategy.doRecovery = Attempting to PeerSync from http://127.0.0.1:36944/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 15411 T135 C8 P12591 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:12591/solr START replicas=3D[http://1= 27.0.0.1:36944/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 15411 T58 oass.SolrDispatchFilter.init user.dir=3D/usr= /home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 15411 T135 C8 P12591 oasu.PeerSync.sync WARNING no fra= me of reference to tell of we've missed updates [junit4:junit4] 2> 15411 T58 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4:junit4] 2> 15411 T135 C8 P12591 oasc.RecoveryStrategy.doRecovery = PeerSync Recovery was not successful - trying replication. core=3Dcollectio= n1 [junit4:junit4] 2> 15412 T135 C8 P12591 oasc.RecoveryStrategy.doRecovery = Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 15412 T58 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 15412 T135 C8 P12591 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnections=3D128&maxConnectionsPerHo= st=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 15414 T85 C6 P36944 REQ /get {distrib=3Dfalse&qt=3D/ge= t&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 15428 T58 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:27791/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@423252d6 [junit4:junit4] 2> 15429 T58 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 15429 T137 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 15430 T137 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:27791, initiating sessi= on [junit4:junit4] 2> 15430 T60 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:60013 [junit4:junit4] 2> 15431 T60 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:60013 [junit4:junit4] 2> 15432 T62 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139cd7bd13e0007 with negotiated timeout 10000 for client /14= 0.211.11.196:60013 [junit4:junit4] 2> 15432 T137 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:27791, sessio= nid =3D 0x139cd7bd13e0007, negotiated timeout =3D 10000 [junit4:junit4] 2> 15433 T138 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@423252d6 name:ZooKeeperConnecti= on Watcher:127.0.0.1:27791/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 15433 T58 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 15434 T58 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 15437 T58 oasc.ChaosMonkey.monkeyLog monkey: init - ex= pire sessions:true cause connection loss:true [junit4:junit4] 2> 15438 T58 oascsi.HttpClientUtil.createClient Creating = new http client, config: [junit4:junit4] 2> 15446 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15447 T58 oasc.AbstractFullDistribZkTestBase.waitForTh= ingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt [junit4:junit4] 2> 15448 T58 oasc.AbstractDistribZkTestBase.waitForRecove= riesToFinish Wait for recoveries to finish - collection: collection1 failOn= Timeout:true timeout (sec):15 [junit4:junit4] 2> 15449 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15611 T80 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15626 T119 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15626 T105 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15626 T138 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15626 T79 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 15626 T133 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 15626 T93 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 16451 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> C7_STATE=3Dcoll:collection1 core:collection1 props:{s= hard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, collec= tion=3Dcollection1, node_name=3D127.0.0.1:14671_solr, base_url=3Dhttp://127= .0.0.1:14671/solr} [junit4:junit4] 2> 16524 T121 C7 P14671 oasc.RecoveryStrategy.doRecovery = Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 16524 T121 C7 P14671 oasu.UpdateLog.bufferUpdates Star= ting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 16524 T121 C7 P14671 oasc.RecoveryStrategy.replicate A= ttempting to replicate from http://127.0.0.1:36944/solr/collection1/. core= =3Dcollection1 [junit4:junit4] 2> 16525 T121 C7 P14671 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnections=3D128&maxConnectionsPerHo= st=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 16533 T91 C6 P36944 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16536 T91 C6 P36944 oass.SolrIndexSearcher. Open= ing Searcher@53ffb7d4 main [junit4:junit4] 2> 16536 T91 C6 P36944 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 16538 T94 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@53ffb7d4 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 16538 T91 C6 P36944 /update {waitSearcher=3Dtrue&commi= t=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version=3D= 2} {commit=3D} 0 5 [junit4:junit4] 2> 16539 T121 C7 P14671 oash.ReplicationHandler.inform Co= mmits will be reserved for 10000 [junit4:junit4] 2> 16540 T121 C7 P14671 oash.SnapPuller. No value = set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 16542 T89 C6 P36944 REQ /replication {command=3Dindexv= ersion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 16546 T121 C7 P14671 oasc.SolrDeletionPolicy.onInit So= lrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty3/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@53e36ec6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 16546 T121 C7 P14671 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 1 [junit4:junit4] 2> 16547 T121 C7 P14671 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16575 T121 C7 P14671 oasc.SolrDeletionPolicy.onCommit = SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty3/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@53e36ec6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty3/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@53e36ec6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 16576 T121 C7 P14671 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 2 [junit4:junit4] 2> 16578 T121 C7 P14671 oass.SolrIndexSearcher. Ope= ning Searcher@19ad0dd8 main [junit4:junit4] 2> 16578 T121 C7 P14671 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 16580 T120 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@19ad0dd8 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 16580 T121 C7 P14671 oasc.RecoveryStrategy.replay No r= eplay needed. core=3Dcollection1 [junit4:junit4] 2> 16581 T121 C7 P14671 oasc.RecoveryStrategy.doRecovery = Replication Recovery was successful - registering as Active. core=3Dcollect= ion1 [junit4:junit4] 2> 16582 T121 C7 P14671 oasc.RecoveryStrategy.doRecovery = Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 16632 T80 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 16645 T105 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 16645 T133 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 16645 T93 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 16645 T79 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 16645 T138 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 16645 T119 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 17453 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> C8_STATE=3Dcoll:collection1 core:collection1 props:{s= hard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, collec= tion=3Dcollection1, node_name=3D127.0.0.1:12591_solr, base_url=3Dhttp://127= .0.0.1:12591/solr} [junit4:junit4] 2> 18427 T135 C8 P12591 oasc.RecoveryStrategy.doRecovery = Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 18427 T135 C8 P12591 oasu.UpdateLog.bufferUpdates Star= ting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 18427 T135 C8 P12591 oasc.RecoveryStrategy.replicate A= ttempting to replicate from http://127.0.0.1:36944/solr/collection1/. core= =3Dcollection1 [junit4:junit4] 2> 18428 T135 C8 P12591 oascsi.HttpClientUtil.createClien= t Creating new http client, config:maxConnections=3D128&maxConnectionsPerHo= st=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 18442 T86 C6 P36944 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18444 T86 C6 P36944 oass.SolrIndexSearcher. Open= ing Searcher@7aa5f9b main [junit4:junit4] 2> 18445 T86 C6 P36944 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 18447 T94 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@7aa5f9b main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 18448 T86 C6 P36944 /update {waitSearcher=3Dtrue&commi= t=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version=3D= 2} {commit=3D} 0 6 [junit4:junit4] 2> 18449 T135 C8 P12591 oash.ReplicationHandler.inform Co= mmits will be reserved for 10000 [junit4:junit4] 2> 18449 T135 C8 P12591 oash.SnapPuller. No value = set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 18452 T89 C6 P36944 REQ /replication {command=3Dindexv= ersion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18456 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 18457 T135 C8 P12591 oasc.SolrDeletionPolicy.onInit So= lrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty4/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@3dad8690; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 18457 T135 C8 P12591 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 1 [junit4:junit4] 2> 18458 T135 C8 P12591 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18528 T135 C8 P12591 oasc.SolrDeletionPolicy.onCommit = SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty4/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@3dad8690; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty4/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@3dad8690; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 18528 T135 C8 P12591 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 2 [junit4:junit4] 2> 18531 T135 C8 P12591 oass.SolrIndexSearcher. Ope= ning Searcher@1b92d8d6 main [junit4:junit4] 2> 18531 T135 C8 P12591 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 18534 T134 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1b92d8d6 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 18535 T135 C8 P12591 oasc.RecoveryStrategy.replay No r= eplay needed. core=3Dcollection1 [junit4:junit4] 2> 18536 T135 C8 P12591 oasc.RecoveryStrategy.doRecovery = Replication Recovery was successful - registering as Active. core=3Dcollect= ion1 [junit4:junit4] 2> 18538 T135 C8 P12591 oasc.RecoveryStrategy.doRecovery = Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 18656 T80 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 18669 T138 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 18669 T119 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 18669 T93 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 18669 T79 oascc.ZkStateReader$2.process A cluster stat= e change has occurred - updating... [junit4:junit4] 2> 18669 T133 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 18669 T105 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 19460 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> ASYNC NEW_CORE C9 name=3Dcollection1 org.apache.solr.= core.SolrCore@2b0a141e url=3Dhttp://127.0.0.1:34961/solr/collection1 node= =3D127.0.0.1:34961_solr [junit4:junit4] 2> 19473 T71 C9 P34961 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19477 T71 C9 P34961 oasc.SolrDeletionPolicy.onInit Sol= rDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/control/data/index lockFactory=3Dorg.apache.lucene.s= tore.NativeFSLockFactory@37f2ae62; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),= segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 19477 T71 C9 P34961 oasc.SolrDeletionPolicy.updateComm= its newest commit =3D 1 [junit4:junit4] 2> 19478 T71 C9 P34961 oass.SolrIndexSearcher. Open= ing Searcher@10ad1355 main [junit4:junit4] 2> 19479 T71 C9 P34961 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 19480 T82 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@10ad1355 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 19481 T71 C9 P34961 /update {waitSearcher=3Dtrue&wt=3D= javabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 8 [junit4:junit4] 2> 19482 T58 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:27791/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@52d03a00 [junit4:junit4] 2> 19483 T139 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:27791 [junit4:junit4] 2> 19483 T58 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 19484 T139 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:27791, initiating sessi= on [junit4:junit4] 2> 19484 T60 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:57193 [junit4:junit4] 2> 19484 T60 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:57193 [junit4:junit4] 2> 19515 T62 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139cd7bd13e0008 with negotiated timeout 10000 for client /14= 0.211.11.196:57193 [junit4:junit4] 2> 19515 T139 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:27791, sessio= nid =3D 0x139cd7bd13e0008, negotiated timeout =3D 10000 [junit4:junit4] 2> 19515 T140 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@52d03a00 name:ZooKeeperConnecti= on Watcher:127.0.0.1:27791/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 19516 T58 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 19517 T58 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 19529 T88 C6 P36944 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19532 T88 C6 P36944 oass.SolrIndexSearcher. Open= ing Searcher@21397218 main [junit4:junit4] 2> 19532 T88 C6 P36944 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 19533 T94 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@21397218 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 19544 T88 C6 P36944 oasu.SolrCmdDistributor.distribCom= mit Distrib commit to:[StdNode: http://127.0.0.1:10606/solr/collection1/, S= tdNode: http://127.0.0.1:14671/solr/collection1/, StdNode: http://127.0.0.1= :12591/solr/collection1/] [junit4:junit4] 2> C5_STATE=3Dcoll:collection1 core:collection1 props:{s= hard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collection= =3Dcollection1, node_name=3D127.0.0.1:10606_solr, base_url=3Dhttp://127.0.0= .1:10606/solr} [junit4:junit4] 2> 19563 T97 C5 P10606 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> C7_STATE=3Dcoll:collection1 core:collection1 props:{s= hard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collection= =3Dcollection1, node_name=3D127.0.0.1:14671_solr, base_url=3Dhttp://127.0.0= .1:14671/solr} [junit4:junit4] 2> 19563 T111 C7 P14671 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> C8_STATE=3Dcoll:collection1 core:collection1 props:{s= hard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collection= =3Dcollection1, node_name=3D127.0.0.1:12591_solr, base_url=3Dhttp://127.0.0= .1:12591/solr} [junit4:junit4] 2> 19565 T125 C8 P12591 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 19566 T97 C5 P10606 oass.SolrIndexSearcher. Open= ing Searcher@43be87a0 main [junit4:junit4] 2> 19567 T111 C7 P14671 oass.SolrIndexSearcher. Ope= ning Searcher@68ed8363 main [junit4:junit4] 2> 19567 T97 C5 P10606 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 19567 T111 C7 P14671 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 19568 T125 C8 P12591 oass.SolrIndexSearcher. Ope= ning Searcher@38c83cfd main [junit4:junit4] 2> 19569 T106 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@43be87a0 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 19569 T125 C8 P12591 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 19569 T97 C5 P10606 /update {waitSearcher=3Dtrue&commi= t=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softCo= mmit=3Dfalse&version=3D2} {commit=3D} 0 6 [junit4:junit4] 2> 19569 T120 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@68ed8363 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 19570 T111 C7 P14671 /update {waitSearcher=3Dtrue&comm= it=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softC= ommit=3Dfalse&version=3D2} {commit=3D} 0 7 [junit4:junit4] 2> 19570 T134 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@38c83cfd main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 19571 T125 C8 P12591 /update {waitSearcher=3Dtrue&comm= it=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softC= ommit=3Dfalse&version=3D2} {commit=3D} 0 6 [junit4:junit4] 2> 19572 T88 C6 P36944 /update {waitSearcher=3Dtrue&wt=3D= javabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 43 [junit4:junit4] 2> 19573 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 19767 T87 C6 P36944 REQ /select {distrib=3Dfalse&wt=3D= javabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D179=20 [junit4:junit4] 2> 19776 T98 C5 P10606 REQ /select {distrib=3Dfalse&wt=3D= javabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 19779 T112 C7 P14671 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 19782 T126 C8 P12591 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 21895 T71 C9 P34961 oass.SolrIndexSearcher. Open= ing Searcher@2d15fd37 realtime [junit4:junit4] 2> 21898 T71 C9 P34961 /update {wt=3Djavabin&version=3D2}= {deleteByQuery=3D*:* (-1413241613067485184)} 0 58 [junit4:junit4] 2> 21903 T88 C6 P36944 oass.SolrIndexSearcher. Open= ing Searcher@4226c7da realtime [junit4:junit4] 2> 21913 T111 C7 P14671 oass.SolrIndexSearcher. Ope= ning Searcher@bb51061 realtime [junit4:junit4] 2> 21913 T97 C5 P10606 oass.SolrIndexSearcher. Open= ing Searcher@1c465648 realtime [junit4:junit4] 2> 21913 T125 C8 P12591 oass.SolrIndexSearcher. Ope= ning Searcher@31aba72e realtime [junit4:junit4] 2> 21913 T111 C7 P14671 REQ /update {update.distrib=3DFRO= MLEADER&_version_=3D-1413241613107331072&update.from=3Dhttp://127.0.0.1:369= 44/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 21914 T97 C5 P10606 REQ /update {update.distrib=3DFROM= LEADER&_version_=3D-1413241613107331072&update.from=3Dhttp://127.0.0.1:3694= 4/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 21914 T125 C8 P12591 REQ /update {update.distrib=3DFRO= MLEADER&_version_=3D-1413241613107331072&update.from=3Dhttp://127.0.0.1:369= 44/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 21915 T88 C6 P36944 /update {wt=3Djavabin&version=3D2}= {deleteByQuery=3D*:* (-1413241613107331072)} 0 14 [junit4:junit4] 2> 21941 T71 C9 P34961 /update {wt=3Djavabin&version=3D2}= {add=3D[0 (1413241613129351168)]} 0 23 [junit4:junit4] 2> 21962 T111 C7 P14671 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavabi= n&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 21962 T97 C5 P10606 REQ /update {distrib.from=3Dhttp:/= /127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavabin= &version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 21962 T125 C8 P12591 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavabi= n&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 21963 T88 C6 P36944 /update {wt=3Djavabin&version=3D2}= {add=3D[0 (1413241613152419840)]} 0 19 [junit4:junit4] 2> 21970 T71 C9 P34961 /update {wt=3Djavabin&version=3D2}= {add=3D[1 (1413241613175488512)]} 0 3 [junit4:junit4] 2> 21981 T111 C7 P14671 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavabi= n&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 21981 T125 C8 P12591 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavabi= n&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 21981 T97 C5 P10606 REQ /update {distrib.from=3Dhttp:/= /127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djavabin= &version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 21982 T88 C6 P36944 /update {wt=3Djavabin&version=3D2}= {add=3D[1 (1413241613182828544)]} 0 9 [junit4:junit4] 2> 21988 T71 C9 P34961 /update {wt=3Djavabin&version=3D2}= {add=3D[2 (1413241613195411456)]} 0 3 [junit4:junit4] 2> 21999 T111 C7 P14671 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&test.distri= b.skip.servers=3Dhttp://127.0.0.1:10606/solr/collection1/&wt=3Djavabin&vers= ion=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 21999 T125 C8 P12591 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&test.distri= b.skip.servers=3Dhttp://127.0.0.1:10606/solr/collection1/&wt=3Djavabin&vers= ion=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 22000 T88 C6 P36944 /update {test.distrib.skip.servers= =3Dhttp://127.0.0.1:10606/solr/collection1/&wt=3Djavabin&version=3D2} {add= =3D[2 (1413241613201702912)]} 0 8 [junit4:junit4] 2> 22004 T71 C9 P34961 /update {wt=3Djavabin&version=3D2}= {add=3D[3 (1413241613213237248)]} 0 2 [junit4:junit4] 2> 22014 T125 C8 P12591 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&test.distri= b.skip.servers=3Dhttp://127.0.0.1:10606/solr/collection1/&test.distrib.skip= .servers=3Dhttp://127.0.0.1:14671/solr/collection1/&wt=3Djavabin&version=3D= 2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 22014 T88 C6 P36944 /update {test.distrib.skip.servers= =3Dhttp://127.0.0.1:10606/solr/collection1/&test.distrib.skip.servers=3Dhtt= p://127.0.0.1:14671/solr/collection1/&wt=3Djavabin&version=3D2} {add=3D[3 (= 1413241613218480128)]} 0 7 [junit4:junit4] 2> 22018 T71 C9 P34961 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22188 T71 C9 P34961 oasc.SolrDeletionPolicy.onCommit S= olrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/control/data/index lockFactory=3Dorg.apache.lucene.s= tore.NativeFSLockFactory@37f2ae62; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),= segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/control/data/index lockFactory=3Dorg.apache.lucene.s= tore.NativeFSLockFactory@37f2ae62; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),= segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_MockRandom_0.tib, _0.fnm,= _0_MockVariableIntBlock_0.doc, _0_Memory_0.ram, _0_MockRandom_0.prx, _0_Ne= stedPulsing_0.frq, _0_nrm.cfs, _0_MockVariableIntBlock_0.skp, _0_NestedPuls= ing_0.tip, _0_NestedPulsing_0.prx, _0_NestedPulsing_0.tim, _0_MockVariableI= ntBlock_0.pyl, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tiv= , _0_MockVariableIntBlock_0.tib, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe,= _0_MockVariableIntBlock_0.pos, segments_2, _0.fdx, _0.si, _0_MockVariableI= ntBlock_0.tii, _0.fdt] [junit4:junit4] 2> 22189 T71 C9 P34961 oasc.SolrDeletionPolicy.updateComm= its newest commit =3D 2 [junit4:junit4] 2> 22250 T71 C9 P34961 oass.SolrIndexSearcher. Open= ing Searcher@1a055ff4 main [junit4:junit4] 2> 22251 T71 C9 P34961 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 22253 T82 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@1a055ff4 main{StandardDirectoryReader(seg= ments_2:3:nrt _0(5.0):C4)} [junit4:junit4] 2> 22253 T71 C9 P34961 /update {waitSearcher=3Dtrue&wt=3D= javabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 235 [junit4:junit4] 2> 22256 T88 C6 P36944 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22320 T88 C6 P36944 oasc.SolrDeletionPolicy.onCommit S= olrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty1/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@c39a867; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty1/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@c39a867; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[_0_MockVariableIntBlock_0.doc, _0= _MockVariableIntBlock_0.skp, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim= , _0_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _0_MockVari= ableIntBlock_0.pos, _0.si, _0_MockVariableIntBlock_0.tii, _0_MockRandom_0.t= ib, _0.fnm, _0_Memory_0.ram, _0_NestedPulsing_0.frq, _0_nrm.cfs, _0_NestedP= ulsing_0.prx, _0_MockRandom_0.skp, _0_MockRandom_0.sd, _0_MockRandom_0.frq,= _0_MockRandom_0.tiv, _0_MockRandom_0.doc, _0_MockVariableIntBlock_0.frq, _= 0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0.fdx, _0_MockRandom_0.pyl, _0= .fdt] [junit4:junit4] 2> 22320 T88 C6 P36944 oasc.SolrDeletionPolicy.updateComm= its newest commit =3D 2 [junit4:junit4] 2> 22341 T88 C6 P36944 oass.SolrIndexSearcher. Open= ing Searcher@5054c2b8 main [junit4:junit4] 2> 22342 T88 C6 P36944 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 22383 T94 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@5054c2b8 main{StandardDirectoryReader(seg= ments_2:3:nrt _0(5.0):C4)} [junit4:junit4] 2> 22384 T88 C6 P36944 oasu.SolrCmdDistributor.distribCom= mit Distrib commit to:[StdNode: http://127.0.0.1:10606/solr/collection1/, S= tdNode: http://127.0.0.1:14671/solr/collection1/, StdNode: http://127.0.0.1= :12591/solr/collection1/] [junit4:junit4] 2> 22388 T125 C8 P12591 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22388 T111 C7 P14671 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22388 T97 C5 P10606 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22522 T125 C8 P12591 oasc.SolrDeletionPolicy.onCommit = SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty4/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@3dad8690; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty4/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@3dad8690; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_3,generation=3D3,filenames=3D[_0_MockVariableIntBlock_0.doc, _0= _MockVariableIntBlock_0.skp, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim= , _0_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _0_MockVari= ableIntBlock_0.pos, _0.si, _0_MockVariableIntBlock_0.tii, _0_MockRandom_0.t= ib, _0.fnm, _0_Memory_0.ram, _0_NestedPulsing_0.frq, _0_nrm.cfs, _0_NestedP= ulsing_0.prx, _0_MockRandom_0.skp, _0_MockRandom_0.sd, _0_MockRandom_0.frq,= _0_MockRandom_0.doc, _0_MockVariableIntBlock_0.frq, _0_MockRandom_0.pos, _= 0_nrm.cfe, _0_MockRandom_0.tii, _0.fdx, _0_MockRandom_0.pyl, _0.fdt, segmen= ts_3] [junit4:junit4] 2> 22522 T97 C5 P10606 oasc.SolrDeletionPolicy.onCommit S= olrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty2/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@153bcbc8; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty2/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@153bcbc8; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_3,generation=3D3,filenames=3D[_0_MockVariableIntBlock_0.doc, _0= _MockVariableIntBlock_0.skp, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim= , _0_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _0_MockVari= ableIntBlock_0.pos, _0.si, _0_MockVariableIntBlock_0.tii, _0.fnm, _0_Memory= _0.ram, _0_NestedPulsing_0.frq, _0_nrm.cfs, _0_NestedPulsing_0.prx, _0_Mock= Random_0.skp, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tip,= _0_MockRandom_0.doc, _0_MockVariableIntBlock_0.frq, _0_MockRandom_0.pos, _= 0_nrm.cfe, _0.fdx, _0_MockRandom_0.pyl, _0_MockRandom_0.tim, _0.fdt, segmen= ts_3] [junit4:junit4] 2> 22523 T125 C8 P12591 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 3 [junit4:junit4] 2> 22523 T111 C7 P14671 oasc.SolrDeletionPolicy.onCommit = SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty3/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@53e36ec6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty3/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@53e36ec6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_3,generation=3D3,filenames=3D[_0_MockRandom_0.tib, _0.fnm, _0_M= ockVariableIntBlock_0.doc, _0_Memory_0.ram, _0_MockRandom_0.prx, _0_NestedP= ulsing_0.frq, _0_nrm.cfs, _0_MockVariableIntBlock_0.skp, _0_NestedPulsing_0= .tip, _0_NestedPulsing_0.prx, _0_NestedPulsing_0.tim, _0_MockVariableIntBlo= ck_0.pyl, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockVariableIntBlock_= 0.tib, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, _0_MockVariableIntBlock_0= .pos, _0_MockRandom_0.tii, _0.fdx, _0.si, _0_MockVariableIntBlock_0.tii, _0= .fdt, segments_3] [junit4:junit4] 2> 22523 T97 C5 P10606 oasc.SolrDeletionPolicy.updateComm= its newest commit =3D 3 [junit4:junit4] 2> 22524 T111 C7 P14671 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 3 [junit4:junit4] 2> 22536 T97 C5 P10606 oass.SolrIndexSearcher. Open= ing Searcher@3162a60a main [junit4:junit4] 2> 22537 T97 C5 P10606 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 22538 T106 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@3162a60a main{StandardDirectoryReader(se= gments_3:4:nrt _0(5.0):C2)} [junit4:junit4] 2> 22538 T97 C5 P10606 /update {waitSearcher=3Dtrue&commi= t=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softCo= mmit=3Dfalse&version=3D2} {commit=3D} 0 150 [junit4:junit4] 2> 22548 T125 C8 P12591 oass.SolrIndexSearcher. Ope= ning Searcher@7c2fbdd2 main [junit4:junit4] 2> 22549 T125 C8 P12591 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 22549 T111 C7 P14671 oass.SolrIndexSearcher. Ope= ning Searcher@26f50154 main [junit4:junit4] 2> 22550 T111 C7 P14671 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 22551 T134 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@7c2fbdd2 main{StandardDirectoryReader(se= gments_3:4:nrt _0(5.0):C4)} [junit4:junit4] 2> 22551 T120 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@26f50154 main{StandardDirectoryReader(se= gments_3:4:nrt _0(5.0):C3)} [junit4:junit4] 2> 22551 T125 C8 P12591 /update {waitSearcher=3Dtrue&comm= it=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softC= ommit=3Dfalse&version=3D2} {commit=3D} 0 163 [junit4:junit4] 2> 22552 T111 C7 P14671 /update {waitSearcher=3Dtrue&comm= it=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softC= ommit=3Dfalse&version=3D2} {commit=3D} 0 164 [junit4:junit4] 2> 22553 T88 C6 P36944 /update {waitSearcher=3Dtrue&wt=3D= javabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 297 [junit4:junit4] 2> 22553 T58 oasc.AbstractDistribZkTestBase.waitForRecove= riesToFinish Wait for recoveries to finish - collection: collection1 failOn= Timeout:true timeout (sec):1800 [junit4:junit4] 2> 22555 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> check const of shard1 [junit4:junit4] 2> client0 [junit4:junit4] 2> PROPS:shard=3Dshard1 [junit4:junit4] 2> roles=3Dnull [junit4:junit4] 2> leader=3Dtrue [junit4:junit4] 2> state=3Dactive [junit4:junit4] 2> core=3Dcollection1 [junit4:junit4] 2> collection=3Dcollection1 [junit4:junit4] 2> node_name=3D127.0.0.1:36944_solr [junit4:junit4] 2> base_url=3Dhttp://127.0.0.1:36944/solr [junit4:junit4] 2>=20 [junit4:junit4] 2> 22559 T87 C6 P36944 REQ /select {distrib=3Dfalse&wt=3D= javabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client1 [junit4:junit4] 2> PROPS:shard=3Dshard1 [junit4:junit4] 2> roles=3Dnull [junit4:junit4] 2> state=3Dactive [junit4:junit4] 2> core=3Dcollection1 [junit4:junit4] 2> collection=3Dcollection1 [junit4:junit4] 2> node_name=3D127.0.0.1:10606_solr [junit4:junit4] 2> base_url=3Dhttp://127.0.0.1:10606/solr [junit4:junit4] 2>=20 [junit4:junit4] 2> 22565 T98 C5 P10606 REQ /select {distrib=3Dfalse&wt=3D= javabin&q=3D*:*&version=3D2} hits=3D2 status=3D0 QTime=3D1=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:2 [junit4:junit4] 2>=20 [junit4:junit4] 2> ######shard1 is not consistent. Got 4 from http://127= .0.0.1:36944/solr/collection1lastClient and got 2 from http://127.0.0.1:106= 06/solr/collection1 [junit4:junit4] 2> 22762 T87 C6 P36944 REQ /select {fl=3Did,_version_&sor= t=3Did+asc&q=3D*:*&distrib=3Dfalse&wt=3Djavabin&rows=3D1000&version=3D2} hi= ts=3D4 status=3D0 QTime=3D193=20 [junit4:junit4] 2> 22767 T98 C5 P10606 REQ /select {fl=3Did,_version_&sor= t=3Did+asc&q=3D*:*&distrib=3Dfalse&wt=3Djavabin&rows=3D1000&version=3D2} hi= ts=3D2 status=3D0 QTime=3D1=20 [junit4:junit4] 2> ######http://127.0.0.1:36944/solr/collection1: {numFou= nd=3D4,start=3D0,docs=3D[SolrDocument{id=3D0, _version_=3D14132416131524198= 40}, SolrDocument{id=3D1, _version_=3D1413241613182828544}, SolrDocument{id= =3D2, _version_=3D1413241613201702912}, SolrDocument{id=3D3, _version_=3D14= 13241613218480128}]} [junit4:junit4] 2> ######http://127.0.0.1:10606/solr/collection1: {numFou= nd=3D2,start=3D0,docs=3D[SolrDocument{id=3D0, _version_=3D14132416131524198= 40}, SolrDocument{id=3D1, _version_=3D1413241613182828544}]} [junit4:junit4] 2> ###### sizes=3D4,2 [junit4:junit4] 2> ###### Only in http://127.0.0.1:36944/solr/collection1= : [{id=3D2, _version_=3D1413241613201702912}, {id=3D3, _version_=3D14132416= 13218480128}] [junit4:junit4] 2> client2 [junit4:junit4] 2> PROPS:shard=3Dshard1 [junit4:junit4] 2> roles=3Dnull [junit4:junit4] 2> state=3Dactive [junit4:junit4] 2> core=3Dcollection1 [junit4:junit4] 2> collection=3Dcollection1 [junit4:junit4] 2> node_name=3D127.0.0.1:14671_solr [junit4:junit4] 2> base_url=3Dhttp://127.0.0.1:14671/solr [junit4:junit4] 2>=20 [junit4:junit4] 2> 22771 T112 C7 P14671 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D3 status=3D0 QTime=3D1=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:3 [junit4:junit4] 2>=20 [junit4:junit4] 2> client3 [junit4:junit4] 2> PROPS:shard=3Dshard1 [junit4:junit4] 2> roles=3Dnull [junit4:junit4] 2> state=3Dactive [junit4:junit4] 2> core=3Dcollection1 [junit4:junit4] 2> collection=3Dcollection1 [junit4:junit4] 2> node_name=3D127.0.0.1:12591_solr [junit4:junit4] 2> base_url=3Dhttp://127.0.0.1:12591/solr [junit4:junit4] 2>=20 [junit4:junit4] 2> 22776 T126 C8 P12591 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> 22781 T58 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fol= lowRedirects=3Dfalse [junit4:junit4] 2> 22799 T113 oasha.CollectionsHandler.handleSyncShardAct= ion Syncing shard : action=3DSYNCSHARD&collection=3Dcollection1&shard=3Dsha= rd1&wt=3Djavabin&version=3D2 [junit4:junit4] 2> 22800 T113 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 22814 T90 oasha.CoreAdminHandler.handleRequestSyncActi= on I have been requested to sync up my shard [junit4:junit4] 2> 22815 T90 oasc.SyncStrategy.sync Sync replicas to http= ://127.0.0.1:36944/solr/collection1/ [junit4:junit4] 2> 22825 T90 oasu.PeerSync.sync PeerSync: core=3Dcollecti= on1 url=3Dhttp://127.0.0.1:36944/solr START replicas=3D[http://127.0.0.1:10= 606/solr/collection1/, http://127.0.0.1:14671/solr/collection1/, http://127= .0.0.1:12591/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 22841 T127 C8 P12591 REQ /get {distrib=3Dfalse&qt=3D/g= et&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D11=20 [junit4:junit4] 2> 22841 T99 C5 P10606 REQ /get {distrib=3Dfalse&qt=3D/ge= t&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D11=20 [junit4:junit4] 2> 22841 T114 C7 P14671 REQ /get {distrib=3Dfalse&qt=3D/g= et&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D11=20 [junit4:junit4] 2> 22842 T90 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:36944/solr Received 5 versions from = 127.0.0.1:12591/solr/collection1/ [junit4:junit4] 2> 22843 T90 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:36944/solr Our versions are newer. o= urLowThreshold=3D1413241613107331072 otherHigh=3D1413241613201702912 [junit4:junit4] 2> 22843 T90 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:36944/solr Received 3 versions from = 127.0.0.1:10606/solr/collection1/ [junit4:junit4] 2> 22843 T90 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:36944/solr Our versions are newer. o= urLowThreshold=3D1413241613107331072 otherHigh=3D1413241613182828544 [junit4:junit4] 2> 22844 T90 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:36944/solr Received 4 versions from = 127.0.0.1:14671/solr/collection1/ [junit4:junit4] 2> 22844 T90 oasu.PeerSync.handleVersions PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:36944/solr Our versions are newer. o= urLowThreshold=3D1413241613107331072 otherHigh=3D1413241613201702912 [junit4:junit4] 2> 22845 T90 oasu.PeerSync.sync PeerSync: core=3Dcollecti= on1 url=3Dhttp://127.0.0.1:36944/solr DONE. sync succeeded [junit4:junit4] 2> 22845 T90 oasc.SyncStrategy.syncReplicas Sync Success = - now sync replicas to me [junit4:junit4] 2> 22845 T90 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 36944/solr/collection1/: try and ask http://127.0.0.1:10606/solr/collection= 1/ to sync [junit4:junit4] 2> 22846 T90 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 36944/solr/collection1/: try and ask http://127.0.0.1:14671/solr/collection= 1/ to sync [junit4:junit4] 2> 22847 T90 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 36944/solr/collection1/: try and ask http://127.0.0.1:12591/solr/collection= 1/ to sync [junit4:junit4] 2> 22850 T115 C7 P14671 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:14671/solr START replicas=3D[http://1= 27.0.0.1:36944/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 22850 T100 C5 P10606 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:10606/solr START replicas=3D[http://1= 27.0.0.1:36944/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 22851 T128 C8 P12591 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:12591/solr START replicas=3D[http://1= 27.0.0.1:36944/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 22854 T85 C6 P36944 REQ /get {distrib=3Dfalse&qt=3D/ge= t&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 22854 T91 C6 P36944 REQ /get {distrib=3Dfalse&qt=3D/ge= t&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 22855 T86 C6 P36944 REQ /get {distrib=3Dfalse&qt=3D/ge= t&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 22856 T100 C5 P10606 oasu.PeerSync.handleVersions Peer= Sync: core=3Dcollection1 url=3Dhttp://127.0.0.1:10606/solr Received 5 vers= ions from 127.0.0.1:36944/solr/collection1/ [junit4:junit4] 2> 22856 T128 C8 P12591 oasu.PeerSync.handleVersions Peer= Sync: core=3Dcollection1 url=3Dhttp://127.0.0.1:12591/solr Received 5 vers= ions from 127.0.0.1:36944/solr/collection1/ [junit4:junit4] 2> 22856 T115 C7 P14671 oasu.PeerSync.handleVersions Peer= Sync: core=3Dcollection1 url=3Dhttp://127.0.0.1:14671/solr Received 5 vers= ions from 127.0.0.1:36944/solr/collection1/ [junit4:junit4] 2> 22857 T128 C8 P12591 oasu.PeerSync.handleVersions Peer= Sync: core=3Dcollection1 url=3Dhttp://127.0.0.1:12591/solr Our versions ar= e newer. ourLowThreshold=3D1413241613107331072 otherHigh=3D1413241613201702= 912 [junit4:junit4] 2> 22856 T100 C5 P10606 oasu.PeerSync.requestUpdates Peer= Sync: core=3Dcollection1 url=3Dhttp://127.0.0.1:10606/solr Requesting updat= es from 127.0.0.1:36944/solr/collection1/n=3D2 versions=3D[1413241613218480= 128, 1413241613201702912] [junit4:junit4] 2> 22857 T115 C7 P14671 oasu.PeerSync.requestUpdates Peer= Sync: core=3Dcollection1 url=3Dhttp://127.0.0.1:14671/solr Requesting updat= es from 127.0.0.1:36944/solr/collection1/n=3D1 versions=3D[1413241613218480= 128] [junit4:junit4] 2> 22857 T128 C8 P12591 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:12591/solr DONE. sync succeeded [junit4:junit4] 2> 22858 T128 C8 P12591 REQ /get {sync=3Dhttp://127.0.0.1= :36944/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt=3Dj= avabin&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 22859 T90 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 36944/solr/collection1/: sync completed with http://127.0.0.1:12591/solr/c= ollection1/ [junit4:junit4] 2> 22861 T85 C6 P36944 REQ /get {distrib=3Dfalse&getUpdat= es=3D1413241613218480128&qt=3D/get&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D0=20 [junit4:junit4] 2> 22861 T86 C6 P36944 REQ /get {distrib=3Dfalse&getUpdat= es=3D1413241613218480128,1413241613201702912&qt=3D/get&wt=3Djavabin&version= =3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 22869 T115 C7 P14671 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:14671/solr DONE. sync succeeded [junit4:junit4] 2> 22869 T115 C7 P14671 REQ /get {sync=3Dhttp://127.0.0.1= :36944/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt=3Dj= avabin&version=3D2} status=3D0 QTime=3D19=20 [junit4:junit4] 2> 22870 T90 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 36944/solr/collection1/: sync completed with http://127.0.0.1:14671/solr/c= ollection1/ [junit4:junit4] 2> 22873 T100 C5 P10606 oasu.PeerSync.sync PeerSync: core= =3Dcollection1 url=3Dhttp://127.0.0.1:10606/solr DONE. sync succeeded [junit4:junit4] 2> 22874 T100 C5 P10606 REQ /get {sync=3Dhttp://127.0.0.1= :36944/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt=3Dj= avabin&version=3D2} status=3D0 QTime=3D24=20 [junit4:junit4] 2> 22875 T90 oasc.SyncStrategy.syncToMe http://127.0.0.1:= 36944/solr/collection1/: sync completed with http://127.0.0.1:10606/solr/c= ollection1/ [junit4:junit4] 2> 22876 T58 oasc.AbstractFullDistribZkTestBase.waitForTh= ingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt [junit4:junit4] 2> 22877 T58 oasc.AbstractDistribZkTestBase.waitForRecove= riesToFinish Wait for recoveries to finish - collection: collection1 failOn= Timeout:true timeout (sec):15 [junit4:junit4] 2> 22878 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 22883 T71 C9 P34961 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22885 T71 C9 P34961 oass.SolrIndexSearcher. Open= ing Searcher@1310c2e8 main [junit4:junit4] 2> 22886 T71 C9 P34961 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 22888 T82 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@1310c2e8 main{StandardDirectoryReader(seg= ments_2:3:nrt _0(5.0):C4)} [junit4:junit4] 2> 22888 T71 C9 P34961 /update {waitSearcher=3Dtrue&wt=3D= javabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 5 [junit4:junit4] 2> 22893 T88 C6 P36944 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22895 T88 C6 P36944 oass.SolrIndexSearcher. Open= ing Searcher@2f620d92 main [junit4:junit4] 2> 22896 T88 C6 P36944 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 22898 T94 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@2f620d92 main{StandardDirectoryReader(seg= ments_2:3:nrt _0(5.0):C4)} [junit4:junit4] 2> 22899 T88 C6 P36944 oasu.SolrCmdDistributor.distribCom= mit Distrib commit to:[StdNode: http://127.0.0.1:10606/solr/collection1/, S= tdNode: http://127.0.0.1:14671/solr/collection1/, StdNode: http://127.0.0.1= :12591/solr/collection1/] [junit4:junit4] 2> 22903 T111 C7 P14671 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22903 T97 C5 P10606 oasu.DirectUpdateHandler2.commit s= tart commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,wa= itSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22903 T125 C8 P12591 oasu.DirectUpdateHandler2.commit = start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue,w= aitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 22907 T125 C8 P12591 oass.SolrIndexSearcher. Ope= ning Searcher@2c591171 main [junit4:junit4] 2> 22908 T125 C8 P12591 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 22910 T134 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@2c591171 main{StandardDirectoryReader(se= gments_3:4:nrt _0(5.0):C4)} [junit4:junit4] 2> 22911 T125 C8 P12591 /update {waitSearcher=3Dtrue&comm= it=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softC= ommit=3Dfalse&version=3D2} {commit=3D} 0 8 [junit4:junit4] 2> 23044 T111 C7 P14671 oasc.SolrDeletionPolicy.onCommit = SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty3/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@53e36ec6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_3,generation=3D3,filenames=3D[_0_MockRandom_0.tib, _0.fnm, _0_M= ockVariableIntBlock_0.doc, _0_Memory_0.ram, _0_MockRandom_0.prx, _0_NestedP= ulsing_0.frq, _0_nrm.cfs, _0_MockVariableIntBlock_0.skp, _0_NestedPulsing_0= .tip, _0_NestedPulsing_0.prx, _0_NestedPulsing_0.tim, _0_MockVariableIntBlo= ck_0.pyl, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockVariableIntBlock_= 0.tib, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, _0_MockVariableIntBlock_0= .pos, _0_MockRandom_0.tii, _0.fdx, _0.si, _0_MockVariableIntBlock_0.tii, _0= .fdt, segments_3] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty3/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@53e36ec6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_4,generation=3D4,filenames=3D[_1_MockVariableIntBlock_0.skp, _1= _NestedPulsing_0.tip, _1_NestedPulsing_0.prx, _0_MockVariableIntBlock_0.doc= , _0_MockRandom_0.prx, _1_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlo= ck_0.skp, _0_NestedPulsing_0.tip, _1.fnm, _0_NestedPulsing_0.tim, _0_MockVa= riableIntBlock_0.pyl, _1_Memory_0.ram, _0_MockVariableIntBlock_0.tib, _1_Mo= ckVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _1_NestedPulsing_0= .tim, _0.si, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _0_MockRandom_0.tib= , _0.fnm, _1_MockVariableIntBlock_0.tii, _0_Memory_0.ram, _1_MockVariableIn= tBlock_0.pos, _1_MockRandom_0.tip, _0_NestedPulsing_0.frq, _1_MockRandom_0.= sd, _1_MockRandom_0.tim, _0_nrm.cfs, _0_NestedPulsing_0.prx, _1_MockVariabl= eIntBlock_0.tib, _1_nrm.cfe, _1_MockVariableIntBlock_0.frq, _1.fdx, _1_Mock= Random_0.prx, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _1_NestedPulsing_0.f= rq, _1.fdt, _1.si, _0_nrm.cfe, _0_MockVariableIntBlock_0.frq, _0_MockRandom= _0.tii, _0.fdx, _1_MockRandom_0.frq, _0.fdt, segments_4] [junit4:junit4] 2> 23044 T97 C5 P10606 oasc.SolrDeletionPolicy.onCommit S= olrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty2/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@153bcbc8; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_3,generation=3D3,filenames=3D[_0_MockVariableIntBlock_0.doc, _0= _MockVariableIntBlock_0.skp, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim= , _0_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _0_MockVari= ableIntBlock_0.pos, _0.si, _0_MockVariableIntBlock_0.tii, _0.fnm, _0_Memory= _0.ram, _0_NestedPulsing_0.frq, _0_nrm.cfs, _0_NestedPulsing_0.prx, _0_Mock= Random_0.skp, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tip,= _0_MockRandom_0.doc, _0_MockVariableIntBlock_0.frq, _0_MockRandom_0.pos, _= 0_nrm.cfe, _0.fdx, _0_MockRandom_0.pyl, _0_MockRandom_0.tim, _0.fdt, segmen= ts_3] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty2/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@153bcbc8; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_4,generation=3D4,filenames=3D[_1_NestedPulsing_0.tip, _0_MockVa= riableIntBlock_0.skp, _1.fnm, _1_NestedPulsing_0.tim, _0.fnm, _0_Memory_0.r= am, _1_MockVariableIntBlock_0.tii, _1_MockRandom_0.tip, _1_MockRandom_0.tim= , _1_MockRandom_0.sd, _0_MockRandom_0.skp, _1_MockVariableIntBlock_0.tib, _= 1.fdx, _0_MockRandom_0.frq, _1.fdt, _0_MockRandom_0.doc, _0_MockRandom_0.po= s, _0_MockVariableIntBlock_0.frq, _1_MockRandom_0.frq, _1_MockVariableIntBl= ock_0.skp, _1_NestedPulsing_0.prx, _0_MockVariableIntBlock_0.doc, _1_MockVa= riableIntBlock_0.pyl, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_Mo= ckVariableIntBlock_0.pyl, _1_Memory_0.ram, _0_MockVariableIntBlock_0.tib, _= 1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _0.si, _0_Mock= VariableIntBlock_0.tii, _1_nrm.cfs, _1_MockVariableIntBlock_0.pos, _0_Neste= dPulsing_0.frq, _0_nrm.cfs, _0_NestedPulsing_0.prx, _1_nrm.cfe, _1_MockVari= ableIntBlock_0.frq, _0_MockRandom_0.tip, _0_MockRandom_0.sd, _1_MockRandom_= 0.prx, _1_NestedPulsing_0.frq, _1.si, _0_nrm.cfe, _0.fdx, _0_MockRandom_0.p= yl, _0_MockRandom_0.tim, _0.fdt, segments_4] [junit4:junit4] 2> 23045 T111 C7 P14671 oasc.SolrDeletionPolicy.updateCom= mits newest commit =3D 4 [junit4:junit4] 2> 23046 T97 C5 P10606 oasc.SolrDeletionPolicy.updateComm= its newest commit =3D 4 [junit4:junit4] 2> 23064 T97 C5 P10606 oass.SolrIndexSearcher. Open= ing Searcher@116dd645 main [junit4:junit4] 2> 23064 T111 C7 P14671 oass.SolrIndexSearcher. Ope= ning Searcher@12feba21 main [junit4:junit4] 2> 23065 T97 C5 P10606 oasu.DirectUpdateHandler2.commit e= nd_commit_flush [junit4:junit4] 2> 23066 T106 oasc.JmxMonitoredMap.put WARNING Failed to = register info bean: searcher javax.management.InstanceNotFoundException: so= lr/collection1:type=3Dsearcher,id=3Dorg.apache.solr.search.SolrIndexSearche= r [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getMBean(DefaultMBeanServerInterceptor.java:1118) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:433) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.unregisterMBean(DefaultMBeanServerInterceptor.java:421) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.unregiste= rMBean(JmxMBeanServer.java:540) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.put(JmxMoni= toredMap.java:138) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.put(JmxMoni= toredMap.java:51) [junit4:junit4] 2> =09at org.apache.solr.search.SolrIndexSearcher.registe= r(SolrIndexSearcher.java:242) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.registerSearcher(S= olrCore.java:1642) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.access$000(SolrCor= e.java:121) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore$7.call(SolrCore.ja= va:1519) [junit4:junit4] 2> =09at java.util.concurrent.FutureTask$Sync.innerRun(Fu= tureTask.java:334) [junit4:junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.j= ava:166) [junit4:junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorke= r(ThreadPoolExecutor.java:1110) [junit4:junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.r= un(ThreadPoolExecutor.java:603) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:679) [junit4:junit4] 2>=20 [junit4:junit4] 2> 23067 T111 C7 P14671 oasu.DirectUpdateHandler2.commit = end_commit_flush [junit4:junit4] 2> 23067 T120 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@12feba21 main{StandardDirectoryReader(se= gments_4:6:nrt _0(5.0):C3 _1(5.0):C1)} [junit4:junit4] 2> 23069 T111 C7 P14671 /update {waitSearcher=3Dtrue&comm= it=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softC= ommit=3Dfalse&version=3D2} {commit=3D} 0 166 [junit4:junit4] 2> 23069 T106 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@116dd645 main{StandardDirectoryReader(se= gments_4:6:nrt _0(5.0):C2 _1(5.0):C2)} [junit4:junit4] 2> 23070 T97 C5 P10606 /update {waitSearcher=3Dtrue&commi= t=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&softCo= mmit=3Dfalse&version=3D2} {commit=3D} 0 167 [junit4:junit4] 2> 23070 T88 C6 P36944 /update {waitSearcher=3Dtrue&wt=3D= javabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 177 [junit4:junit4] 2> 23072 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> 23077 T87 C6 P36944 REQ /select {distrib=3Dfalse&wt=3D= javabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 23082 T98 C5 P10606 REQ /select {distrib=3Dfalse&wt=3D= javabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 23087 T112 C7 P14671 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 23092 T126 C8 P12591 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 25116 T72 C9 P34961 REQ /select {fl=3Did,score&shard.u= rl=3D127.0.0.1:34961/solr/collection1/&NOW=3D1347772232491&start=3D0&q=3D*:= *&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&fsv=3Dtrue&rows=3D10&version= =3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 25155 T72 C9 P34961 REQ /select {shard.url=3D127.0.0.1= :34961/solr/collection1/&NOW=3D1347772232491&q=3D*:*&ids=3D3,2,1,0&distrib= =3Dfalse&isShard=3Dtrue&wt=3Djavabin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 25157 T71 C9 P34961 REQ /select {wt=3Djavabin&q=3D*:*&= version=3D2} hits=3D4 status=3D0 QTime=3D61=20 [junit4:junit4] 2> Control Docs:4 [junit4:junit4] 2> 25159 T58 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4:junit4] 2> check const of shard1 [junit4:junit4] 2> client0 [junit4:junit4] 2> PROPS:shard=3Dshard1 [junit4:junit4] 2> roles=3Dnull [junit4:junit4] 2> leader=3Dtrue [junit4:junit4] 2> state=3Dactive [junit4:junit4] 2> core=3Dcollection1 [junit4:junit4] 2> collection=3Dcollection1 [junit4:junit4] 2> node_name=3D127.0.0.1:36944_solr [junit4:junit4] 2> base_url=3Dhttp://127.0.0.1:36944/solr [junit4:junit4] 2>=20 [junit4:junit4] 2> 25163 T87 C6 P36944 REQ /select {distrib=3Dfalse&wt=3D= javabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client1 [junit4:junit4] 2> PROPS:shard=3Dshard1 [junit4:junit4] 2> roles=3Dnull [junit4:junit4] 2> state=3Dactive [junit4:junit4] 2> core=3Dcollection1 [junit4:junit4] 2> collection=3Dcollection1 [junit4:junit4] 2> node_name=3D127.0.0.1:10606_solr [junit4:junit4] 2> base_url=3Dhttp://127.0.0.1:10606/solr [junit4:junit4] 2>=20 [junit4:junit4] 2> 25167 T98 C5 P10606 REQ /select {distrib=3Dfalse&wt=3D= javabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client2 [junit4:junit4] 2> PROPS:shard=3Dshard1 [junit4:junit4] 2> roles=3Dnull [junit4:junit4] 2> state=3Dactive [junit4:junit4] 2> core=3Dcollection1 [junit4:junit4] 2> collection=3Dcollection1 [junit4:junit4] 2> node_name=3D127.0.0.1:14671_solr [junit4:junit4] 2> base_url=3Dhttp://127.0.0.1:14671/solr [junit4:junit4] 2>=20 [junit4:junit4] 2> 25170 T112 C7 P14671 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client3 [junit4:junit4] 2> PROPS:shard=3Dshard1 [junit4:junit4] 2> roles=3Dnull [junit4:junit4] 2> state=3Dactive [junit4:junit4] 2> core=3Dcollection1 [junit4:junit4] 2> collection=3Dcollection1 [junit4:junit4] 2> node_name=3D127.0.0.1:12591_solr [junit4:junit4] 2> base_url=3Dhttp://127.0.0.1:12591/solr [junit4:junit4] 2>=20 [junit4:junit4] 2> 25174 T126 C8 P12591 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> 25181 T116 C7 P14671 REQ /select {fl=3Did,score&shard.= url=3D127.0.0.1:36944/solr/collection1/|127.0.0.1:10606/solr/collection1/|1= 27.0.0.1:14671/solr/collection1/|127.0.0.1:12591/solr/collection1/&NOW=3D13= 47772232572&start=3D0&q=3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&f= sv=3Dtrue&rows=3D10&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 25216 T116 C7 P14671 REQ /select {shard.url=3D127.0.0.= 1:36944/solr/collection1/|127.0.0.1:10606/solr/collection1/|127.0.0.1:14671= /solr/collection1/|127.0.0.1:12591/solr/collection1/&NOW=3D1347772232572&q= =3D*:*&ids=3D3,2,1,0&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&version=3D= 2} status=3D0 QTime=3D31=20 [junit4:junit4] 2> 25219 T129 C8 P12591 REQ /select {wt=3Djavabin&q=3D*:*= &version=3D2} hits=3D4 status=3D0 QTime=3D41=20 [junit4:junit4] 2> 25228 T71 C9 P34961 /update {wt=3Djavabin&version=3D2}= {add=3D[4 (1413241616589651968)]} 0 5 [junit4:junit4] 2> 25243 T125 C8 P12591 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&test.distri= b.skip.servers=3Dhttp://127.0.0.1:10606/solr/collection1/&wt=3Djavabin&vers= ion=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 25245 T111 C7 P14671 REQ /update {distrib.from=3Dhttp:= //127.0.0.1:36944/solr/collection1/&update.distrib=3DFROMLEADER&test.distri= b.skip.servers=3Dhttp://127.0.0.1:10606/solr/collection1/&wt=3Djavabin&vers= ion=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 25246 T88 C6 P36944 /update {test.distrib.skip.servers= =3Dhttp://127.0.0.1:10606/solr/collection1/&wt=3Djavabin&version=3D2} {add= =3D[4 (1413241616599089152)]} 0 15 [junit4:junit4] 2> 25247 T58 oasc.ChaosMonkey.monkeyLog monkey: kill shar= d! 36944 [junit4:junit4] 2> 25250 T58 oasc.CoreContainer.shutdown Shutting down Co= reContainer instance=3D297690383 [junit4:junit4] 2> 25250 T58 oasc.SolrCore.close [collection1] CLOSING S= olrCore org.apache.solr.core.SolrCore@2ab600af [junit4:junit4] 2> 25275 T58 oasc.SolrCore.closeSearcher [collection1] Cl= osing main searcher on request. [junit4:junit4] 2> 25276 T58 oasu.DirectUpdateHandler2.close closing Dire= ctUpdateHandler2{commits=3D6,autocommits=3D0,soft autocommits=3D0,optimizes= =3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D1,adds=3D1,deletesById= =3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D5,cumulative_deletesBy= Id=3D0,cumulative_deletesByQuery=3D1,cumulative_errors=3D0} [junit4:junit4] 2> 25276 T58 oasu.DefaultSolrCoreState.decref SolrCoreSta= te ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> 25382 T58 C6 P36944 oasc.SolrDeletionPolicy.onCommit S= olrDeletionPolicy.onCommit: commits:num=3D3 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty1/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@c39a867; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-trunk-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncS= liceTest-1347772207407/jetty1/index lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@c39a867; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[_0_MockVariableIntBlock_0.doc, _0= _MockVariableIntBlock_0.skp, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim= , _0_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _0_MockVari= ableIntBlock_0.pos, _0.si, _0_MockVariableIntBlock_0.tii, _ [...truncated too long message...] ter.open(AbstractPooledConnAdapter.java:121) [junit4:junit4] 2> =09at org.apache.http.impl.client.DefaultRequestDirect= or.tryConnect(DefaultRequestDirector.java:575) [junit4:junit4] 2> =09at org.apache.http.impl.client.DefaultRequestDirect= or.execute(DefaultRequestDirector.java:425) [junit4:junit4] 2> =09at org.apache.http.impl.client.AbstractHttpClient.e= xecute(AbstractHttpClient.java:820) [junit4:junit4] 2> =09at org.apache.http.impl.client.AbstractHttpClient.e= xecute(AbstractHttpClient.java:754) [junit4:junit4] 2> =09at org.apache.http.impl.client.AbstractHttpClient.e= xecute(AbstractHttpClient.java:732) [junit4:junit4] 2> =09at org.apache.solr.client.solrj.impl.HttpSolrServer= .request(HttpSolrServer.java:353) [junit4:junit4] 2> =09... 11 more [junit4:junit4] 2>=20 [junit4:junit4] 2> 308732 T354 oasu.PeerSync.sync PeerSync: core=3Dcollec= tion1 url=3Dhttp://127.0.0.1:48586/solr DONE. sync failed [junit4:junit4] 2> 308733 T354 oasc.SolrException.log SEVERE Sync Failed [junit4:junit4] 2> 308733 T354 oasc.ShardLeaderElectionContext.rejoinLead= erElection Not rejoining election because CoreContainer is shutdown [junit4:junit4] 2> 308733 T354 oasc.SolrCore.close [collection1] CLOSING= SolrCore org.apache.solr.core.SolrCore@29dafde4 [junit4:junit4] 2> 308742 T354 oasc.JmxMonitoredMap$SolrDynamicMBean.getM= BeanInfo WARNING Could not getStatistics on info bean Lazy[solr.Replication= Handler] java.lang.IllegalArgumentException: /usr/home/hudson/hudson-slave/= workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.ap= ache.solr.cloud.BasicDistributedZk2Test-1347772452106/jetty4/index does not= exist [junit4:junit4] 2> =09at org.apache.commons.io.FileUtils.sizeOfDirectory(= FileUtils.java:2074) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getIn= dexSize(ReplicationHandler.java:477) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getSt= atistics(ReplicationHandler.java:525) [junit4:junit4] 2> =09at org.apache.solr.core.RequestHandlers$LazyRequest= HandlerWrapper.getStatistics(RequestHandlers.java:310) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap$SolrDynamic= MBean.getMBeanInfo(JmxMonitoredMap.java:231) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getClassName(DefaultMBeanServerInterceptor.java:1824) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1661) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1833) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getAttribute(DefaultMBeanServerInterceptor.java:680) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.getAttrib= ute(JmxMBeanServer.java:672) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.unregister(= JmxMonitoredMap.java:174) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.clear(JmxMo= nitoredMap.java:116) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.close(SolrCore.jav= a:864) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .runLeaderProcess(ElectionContext.java:213) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeader= Process(LeaderElector.java:158) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLe= ader(LeaderElector.java:102) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$000(L= eaderElector.java:56) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector$1.process(Le= aderElector.java:131) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.proc= essEvent(ClientCnxn.java:526) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(= ClientCnxn.java:502) [junit4:junit4] 2>=20 [junit4:junit4] 2> 308743 T354 oasc.JmxMonitoredMap$SolrDynamicMBean.getM= BeanInfo WARNING Could not getStatistics on info bean Lazy[solr.Replication= Handler] java.lang.IllegalArgumentException: /usr/home/hudson/hudson-slave/= workspace/Lucene-Solr-Tests-trunk-Java6/solr/build/solr-core/test/J1/org.ap= ache.solr.cloud.BasicDistributedZk2Test-1347772452106/jetty4/index does not= exist [junit4:junit4] 2> =09at org.apache.commons.io.FileUtils.sizeOfDirectory(= FileUtils.java:2074) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getIn= dexSize(ReplicationHandler.java:477) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getSt= atistics(ReplicationHandler.java:525) [junit4:junit4] 2> =09at org.apache.solr.core.RequestHandlers$LazyRequest= HandlerWrapper.getStatistics(RequestHandlers.java:310) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap$SolrDynamic= MBean.getMBeanInfo(JmxMonitoredMap.java:231) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getClassName(DefaultMBeanServerInterceptor.java:1824) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1661) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1833) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:436) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.unregisterMBean(DefaultMBeanServerInterceptor.java:421) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.unregiste= rMBean(JmxMBeanServer.java:540) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.unregister(= JmxMonitoredMap.java:175) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.clear(JmxMo= nitoredMap.java:116) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.close(SolrCore.jav= a:864) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .runLeaderProcess(ElectionContext.java:213) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeader= Process(LeaderElector.java:158) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLe= ader(LeaderElector.java:102) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$000(L= eaderElector.java:56) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector$1.process(Le= aderElector.java:131) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.proc= essEvent(ClientCnxn.java:526) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(= ClientCnxn.java:502) [junit4:junit4] 2>=20 [junit4:junit4] 2> 308751 T354 oasc.SolrCore.closeSearcher [collection1] = Closing main searcher on request. [junit4:junit4] 2> 308756 T354 oasu.DirectUpdateHandler2.close closing Di= rectUpdateHandler2{commits=3D5,autocommits=3D0,soft autocommits=3D0,optimiz= es=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesByI= d=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D35,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> 308756 T354 oasu.DefaultSolrCoreState.decref SolrCoreS= tate ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> 308758 T354 oasu.DefaultSolrCoreState.decref Closing S= olrCoreState - canceling any ongoing recovery [junit4:junit4] 2> 308759 T354 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 308759 T354 oascc.ZkStateReader$3.process WARNING ZooK= eeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 308760 T354 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 308760 T354 oascc.ZkStateReader$2.process WARNING ZooK= eeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 308760 T354 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@477539b3 name:ZooKeeperConnect= ion Watcher:127.0.0.1:48519/solr got event WatchedEvent state:Disconnected = type:None path:null path:null type:None [junit4:junit4] 2> 308761 T354 oascc.ConnectionManager.process Client->Zo= oKeeper status change trigger but we are already closed [junit4:junit4] 2> 308761 T354 oaz.ClientCnxn$EventThread.run EventThread= shut down [junit4:junit4] 2> NOTE: test params are: codec=3DSimpleText, sim=3DDefau= ltSimilarity, locale=3Dca_ES, timezone=3DPacific/Tahiti [junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. = 1.6.0_32 (64-bit)/cpus=3D16,threads=3D2,free=3D37041208,total=3D135725056 [junit4:junit4] 2> NOTE: All tests run in this JVM: [TestValueSourceCache= , ScriptEngineTest, ClusterStateUpdateTest, TestQuerySenderListener, IndexR= eaderFactoryTest, LukeRequestHandlerTest, TestMergePolicyConfig, SyncSliceT= est, MultiTermTest, BasicZkTest, TestCSVResponseWriter, DocumentBuilderTest= , ReturnFieldsTest, HighlighterTest, StatsComponentTest, TestSolrDeletionPo= licy1, TestPropInjectDefaults, TestFoldingMultitermQuery, BasicDistributedZ= k2Test] [junit4:junit4] Completed on J1 in 309.07s, 1 test, 1 failure <<< FAILURES! [...truncated 457 lines...] BUILD FAILED /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/build= .xml:32: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/solr/= build.xml:146: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucen= e/module-build.xml:63: The following error occurred while executing this li= ne: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucen= e/common-build.xml:1133: The following error occurred while executing this = line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java6/lucen= e/common-build.xml:810: There were test failures: 228 suites, 923 tests, 1 = error, 1 failure, 6 ignored (1 assumption) Total time: 30 minutes 9 seconds Build step 'Invoke Ant' marked build as failure Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_445_2031706218.1347773150456 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_445_2031706218.1347773150456--