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 59BADD3E7 for ; Tue, 18 Sep 2012 18:30:23 +0000 (UTC) Received: (qmail 19655 invoked by uid 500); 18 Sep 2012 18:30:21 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 19604 invoked by uid 500); 18 Sep 2012 18:30:21 -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 19592 invoked by uid 99); 18 Sep 2012 18:30:21 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 18 Sep 2012 18:30:21 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=5.0 tests=ALL_TRUSTED,NORMAL_HTTP_TO_IP,WEIRD_PORT X-Spam-Check-By: apache.org Received: from [140.211.11.8] (HELO aegis.apache.org) (140.211.11.8) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 18 Sep 2012 18:30:00 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id 74210C00A0 for ; Tue, 18 Sep 2012 18:29:38 +0000 (UTC) Date: Tue, 18 Sep 2012 18:29:26 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <675112290.238.1347992978438.JavaMail.hudson@aegis.apache.org> In-Reply-To: <669343400.786.1347901745060.JavaMail.hudson@aegis.apache.org> References: <669343400.786.1347901745060.JavaMail.hudson@aegis.apache.org> Subject: [JENKINS] Lucene-Solr-NightlyTests-4.x - Build # 38 - Still Failing MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_237_1747274640.1347992966360" ------=_Part_237_1747274640.1347992966360 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-4.x/38/ 2 tests failed. FAILED: org.apache.solr.cloud.SyncSliceTest.testDistribSearch Error Message: There are still nodes recoverying Stack Trace: java.lang.AssertionError: There are still nodes recoverying =09at __randomizedtesting.SeedInfo.seed([9266E8D41AC43129:138066CC6D9B5115]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:154) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveries= ToFinish(AbstractFullDistribZkTestBase.java:560) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLe= velOut(AbstractFullDistribZkTestBase.java:1135) =09at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:162) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:695) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:616) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at java.lang.Thread.run(Thread.java:679) FAILED: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch Error Message: Shard still reported as live in zk Stack Trace: java.lang.AssertionError: Shard still reported as live in zk =09at __randomizedtesting.SeedInfo.seed([9266E8D41AC43129:138066CC6D9B5115]= :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) Build Log: [...truncated 7423 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test [junit4:junit4] 2> 3 T1390 oas.SolrTestCaseJ4.setUp ###Starting testDistr= ibSearch [junit4:junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/worksp= ace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-Ba= sicDistributedZk2Test-1347991809383 [junit4:junit4] 2> 4 T1390 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 6 T1391 oazs.ZooKeeperServer.setTickTime tickTime set = to 1000 [junit4:junit4] 2> 6 T1391 oazs.NIOServerCnxn$Factory. binding to p= ort 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 7 T1391 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 105 T1390 oasc.ZkTestServer.run start zk server on por= t:20077 [junit4:junit4] 2> 105 T1390 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:20077 sessionTimeout=3D10000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@3527d2cb [junit4:junit4] 2> 106 T1396 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 106 T1390 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 106 T1396 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:20077, initiating sessio= n [junit4:junit4] 2> 107 T1392 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:20078 [junit4:junit4] 2> 107 T1392 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:20078 [junit4:junit4] 2> 107 T1394 oazsp.FileTxnLog.append Creating new log fil= e: log.1 [junit4:junit4] 2> 112 T1394 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139da92ad6c0000 with negotiated timeout 10000 for client /14= 0.211.11.196:20078 [junit4:junit4] 2> 112 T1396 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:20077, session= id =3D 0x139da92ad6c0000, negotiated timeout =3D 10000 [junit4:junit4] 2> 112 T1397 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@3527d2cb name:ZooKeeperConnectio= n Watcher:127.0.0.1:20077 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4:junit4] 2> 112 T1390 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 112 T1390 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 123 T1395 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139da92ad6c0000 [junit4:junit4] 2> 124 T1397 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 124 T1390 oaz.ZooKeeper.close Session: 0x139da92ad6c00= 00 closed [junit4:junit4] 2> 124 T1392 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:20078 which had sessionid 0x139da92ad6= c0000 [junit4:junit4] 2> 124 T1390 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@618693d1 [junit4:junit4] 2> 125 T1398 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 125 T1390 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 125 T1398 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:20077, initiating sessio= n [junit4:junit4] 2> 125 T1392 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:20079 [junit4:junit4] 2> 125 T1392 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:20079 [junit4:junit4] 2> 127 T1394 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139da92ad6c0001 with negotiated timeout 10000 for client /14= 0.211.11.196:20079 [junit4:junit4] 2> 127 T1398 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:20077, session= id =3D 0x139da92ad6c0001, negotiated timeout =3D 10000 [junit4:junit4] 2> 127 T1399 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@618693d1 name:ZooKeeperConnectio= n Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 127 T1390 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 127 T1390 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4:junit4] 2> 135 T1390 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4:junit4] 2> 137 T1390 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4:junit4] 2> 145 T1390 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4:junit4] 2> 148 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/= solrconfig.xml [junit4:junit4] 2> 148 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 160 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/sche= ma.xml [junit4:junit4] 2> 161 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 264 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/= solrconfig.xml [junit4:junit4] 2> 264 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 267 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/s= topwords.txt [junit4:junit4] 2> 267 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 280 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/p= rotwords.txt [junit4:junit4] 2> 280 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 282 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/cu= rrency.xml [junit4:junit4] 2> 282 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 284 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /conf= igs/conf1/open-exchange-rates.json [junit4:junit4] 2> 285 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 292 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /c= onfigs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 292 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 294 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf= 1/old_synonyms.txt [junit4:junit4] 2> 295 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 297 T1390 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/build/s= olr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/sy= nonyms.txt [junit4:junit4] 2> 297 T1390 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 304 T1395 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139da92ad6c0001 [junit4:junit4] 2> 305 T1399 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 305 T1390 oaz.ZooKeeper.close Session: 0x139da92ad6c00= 01 closed [junit4:junit4] 2> 305 T1392 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:20079 which had sessionid 0x139da92ad6= c0001 [junit4:junit4] 2> 306 T1390 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 314 T1390 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:????? [junit4:junit4] 2> 315 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 315 T1390 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-NightlyTests-4.x/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 315 T1390 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 347 T1390 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 375 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 375 T1390 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-NightlyTests-4.x/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 375 T1390 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nig= htlyTests-4.x/solr/build/solr-core/test-files/solr/solr.xml [junit4:junit4] 2> 376 T1390 oasc.CoreContainer. New CoreContainer = 1504808685 [junit4:junit4] 2> 376 T1390 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nig= htlyTests-4.x/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 376 T1390 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-NightlyTests-4.x/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 394 T1390 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 404 T1390 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:20077/solr [junit4:junit4] 2> 404 T1390 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@22ba4310 [junit4:junit4] 2> 405 T1409 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 405 T1390 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 406 T1409 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:20077, initiating sessio= n [junit4:junit4] 2> 406 T1392 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:20081 [junit4:junit4] 2> 407 T1392 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:20081 [junit4:junit4] 2> 408 T1394 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139da92ad6c0002 with negotiated timeout 5000 for client /140= .211.11.196:20081 [junit4:junit4] 2> 408 T1409 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:20077, session= id =3D 0x139da92ad6c0002, negotiated timeout =3D 5000 [junit4:junit4] 2> 408 T1410 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@22ba4310 name:ZooKeeperConnectio= n Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 408 T1390 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 410 T1395 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139da92ad6c0002 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> 418 T1390 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4:junit4] 2> 419 T1390 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:20080_solr [junit4:junit4] 2> 420 T1395 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139da92ad6c0002 type:dele= te cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:20080_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:20080_solr [junit4:junit4] 2> 426 T1390 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:20080_solr [junit4:junit4] 2> 428 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 428 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 428 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 428 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 429 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 429 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 429 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 430 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 430 T1390 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 430 T1390 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 439 T1390 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4:junit4] 2> 444 T1395 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139da92ad6c0002 type:dele= te cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/ove= rseer_elect/leader [junit4:junit4] 2> 455 T1390 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4:junit4] 2> 457 T1390 oasc.Overseer.start Overseer (id=3D883419912= 20051970-127.0.0.1:20080_solr-n_0000000000) starting [junit4:junit4] 2> 457 T1395 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139da92ad6c0002 type:crea= te cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 458 T1395 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139da92ad6c0002 type:crea= te cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 460 T1395 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139da92ad6c0002 type:crea= te cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 468 T1395 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139da92ad6c0002 type:crea= te cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 469 T1412 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 469 T1390 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4:junit4] 2> 472 T1390 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 473 T1411 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 476 T1390 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-NightlyTests-4.x/solr/build/solr-core/test-files/solr/collection1 [junit4:junit4] 2> 476 T1390 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4:junit4] 2> 476 T1390 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 477 T1390 oasc.ZkController.readConfigName Load collec= tion config from:/collections/control_collection [junit4:junit4] 2> 478 T1390 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-NightlyTests-4.x/solr/build/solr-core/test-files/solr/collection1/' [junit4:junit4] 2> 478 T1390 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTest= s-4.x/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to cla= ssloader [junit4:junit4] 2> 479 T1390 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTest= s-4.x/solr/build/solr-core/test-files/solr/collection1/lib/README' to class= loader [junit4:junit4] 2> 512 T1390 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_40 [junit4:junit4] 2> 558 T1390 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 659 T1390 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 663 T1390 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 1069 T1390 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1077 T1390 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 1080 T1390 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 1088 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1092 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1095 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1096 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1097 T1390 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-4.x/solr/build/solr-core/test-files/solr/collection1/, dataDir=3D./or= g.apache.solr.cloud.BasicDistributedZk2Test-1347991809383/control/data/ [junit4:junit4] 2> 1098 T1390 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@2180b22e [junit4:junit4] 2> 1120 T1390 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347991809383/control/data/index/ [junit4:junit4] 2> 1121 T1390 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47991809383/control/data/index' doesn't exist. Creating new index... [junit4:junit4] 2> 1121 T1390 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-4.x/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistribu= tedZk2Test-1347991809383/control/data/index forceNew:false [junit4:junit4] 2> 1153 T1390 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/control/data/index,segFN=3D= segments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 1154 T1390 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 1156 T1390 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 1159 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 1159 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 1160 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 1161 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 1162 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 1163 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1163 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1165 T1390 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1165 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1166 T1390 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 1167 T1390 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 1168 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1169 T1390 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 1169 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 1170 T1390 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 1171 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1172 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1173 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1174 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1174 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1175 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 1176 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1177 T1390 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1177 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 1178 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 1182 T1390 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 1187 T1390 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 1188 T1390 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> 1192 T1390 oass.SolrIndexSearcher. Opening Searc= her@595188bf main [junit4:junit4] 2> 1193 T1390 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 1194 T1390 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 1194 T1390 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 1202 T1390 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 1259 T1413 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@595188bf main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 1265 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0002 type:cre= ate cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer/queue Error:KeeperErrorCode =3D NoNode for /solr/overseer/= queue [junit4:junit4] 2> 1276 T1390 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 1277 T1390 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:20080/solr shardId:control_shard [junit4:junit4] 2> 1278 T1390 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leader_elect/control_shard/election [junit4:junit4] 2> 1291 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0002 type:del= ete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/collections/control_collection/leaders Error:KeeperErrorCode =3D No= Node for /solr/collections/control_collection/leaders [junit4:junit4] 2> 1292 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 1292 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0002 type:cre= ate cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1481 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1482 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0002 type:cre= ate cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer/queue-work Error:KeeperErrorCode =3D NoNode for /solr/over= seer/queue-work [junit4:junit4] 2> 1497 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 1803 T1390 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 1804 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 1804 T1390 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:20080/solr/collection1/ [junit4:junit4] 2> 1805 T1390 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 1805 T1390 oasc.SyncStrategy.syncToMe http://127.0.0.1= :20080/solr/collection1/ has no replicas [junit4:junit4] 2> 1805 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:20080/solr/collection1/ [junit4:junit4] 2> 1806 T1390 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leaders/control_shard [junit4:junit4] 2> 1822 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0002 type:cre= ate cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2010 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2022 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2033 T1390 oasc.ZkController.register We are http://12= 7.0.0.1:20080/solr/collection1/ and leader is http://127.0.0.1:20080/solr/c= ollection1/ [junit4:junit4] 2> 2033 T1390 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:20080/solr [junit4:junit4] 2> 2034 T1390 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 2035 T1390 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2036 T1390 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 2037 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 2037 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 2196 T1390 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 2199 T1390 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:????? [junit4:junit4] 2> 2200 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 2201 T1390 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1-= 1347991811431 [junit4:junit4] 2> 2201 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1= -1347991811431/' [junit4:junit4] 2> 2235 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 2236 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 2236 T1390 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1-= 1347991811431 [junit4:junit4] 2> 2237 T1390 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1-1347991811431= /solr.xml [junit4:junit4] 2> 2237 T1390 oasc.CoreContainer. New CoreContainer= 1582326970 [junit4:junit4] 2> 2238 T1390 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1-1347991811431= /' [junit4:junit4] 2> 2238 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1-1347991= 811431/' [junit4:junit4] 2> 2262 T1390 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 2276 T1390 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:20077/solr [junit4:junit4] 2> 2277 T1390 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@6064dd87 [junit4:junit4] 2> 2278 T1423 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 2278 T1390 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 2279 T1423 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:20077, initiating sessi= on [junit4:junit4] 2> 2279 T1392 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:20084 [junit4:junit4] 2> 2279 T1392 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:20084 [junit4:junit4] 2> 2281 T1394 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139da92ad6c0003 with negotiated timeout 5000 for client /14= 0.211.11.196:20084 [junit4:junit4] 2> 2282 T1423 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:20077, sessio= nid =3D 0x139da92ad6c0003, negotiated timeout =3D 5000 [junit4:junit4] 2> 2282 T1424 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@6064dd87 name:ZooKeeperConnecti= on Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 2282 T1390 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 2283 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0003 type:cre= ate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2284 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0003 type:cre= ate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2286 T1390 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:20083_solr [junit4:junit4] 2> 2286 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0003 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:20083_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:20083_solr [junit4:junit4] 2> 2301 T1390 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:20083_solr [junit4:junit4] 2> 2303 T1410 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 2303 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 2304 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 2304 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 2304 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 2304 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 2305 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 2305 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 2305 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 2306 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 2306 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 2317 T1390 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 2322 T1390 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1-= 1347991811431/collection1 [junit4:junit4] 2> 2322 T1390 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 2322 T1390 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 2323 T1390 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 2323 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1-1347991= 811431/collection1/' [junit4:junit4] 2> 2324 T1390 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-4.x/solr/build/solr-core/test/J1/solrtest-jetty1-1347991811431/collectio= n1/lib/classes/' to classloader [junit4:junit4] 2> 2325 T1390 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-4.x/solr/build/solr-core/test/J1/solrtest-jetty1-1347991811431/collectio= n1/lib/README' to classloader [junit4:junit4] 2> 2361 T1390 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 2407 T1390 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 2508 T1390 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 2512 T1390 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 2526 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2531 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2531 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2960 T1390 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 2972 T1390 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 2977 T1390 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 2987 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 2992 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 2996 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2997 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2998 T1390 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty1-1347991811431/coll= ection1/, dataDir=3D./org.apache.solr.cloud.BasicDistributedZk2Test-1347991= 809383/jetty1/ [junit4:junit4] 2> 2999 T1390 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@2180b22e [junit4:junit4] 2> 3000 T1390 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347991809383/jetty1/index/ [junit4:junit4] 2> 3001 T1390 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47991809383/jetty1/index' doesn't exist. Creating new index... [junit4:junit4] 2> 3002 T1390 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-4.x/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistribu= tedZk2Test-1347991809383/jetty1/index forceNew:false [junit4:junit4] 2> 3008 T1390 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty1/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 3009 T1390 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 3009 T1390 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 3012 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 3012 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 3013 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 3013 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 3014 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 3015 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 3015 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 3016 T1390 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 3017 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 3018 T1390 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 3018 T1390 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 3019 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 3020 T1390 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 3020 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 3021 T1390 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 3021 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 3022 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3023 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3023 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 3024 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 3025 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 3026 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3026 T1390 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3027 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 3028 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 3031 T1390 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 3036 T1390 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 3037 T1390 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> 3041 T1390 oass.SolrIndexSearcher. Opening Searc= her@36662d65 main [junit4:junit4] 2> 3043 T1390 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 3043 T1390 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 3044 T1390 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 3052 T1390 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 3105 T1425 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@36662d65 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 3544 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3564 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3564 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3613 T1390 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 3613 T1390 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:20083/solr shardId:shard1 [junit4:junit4] 2> 3614 T1390 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 3630 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0003 type:del= ete cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/collections/collection1/leaders Error:KeeperErrorCode =3D NoNode fo= r /solr/collections/collection1/leaders [junit4:junit4] 2> 3631 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 3631 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0003 type:cre= ate cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3633 T1390 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 3633 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 3634 T1390 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:20083/solr/collection1/ [junit4:junit4] 2> 3634 T1390 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 3634 T1390 oasc.SyncStrategy.syncToMe http://127.0.0.1= :20083/solr/collection1/ has no replicas [junit4:junit4] 2> 3635 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:20083/solr/collection1/ [junit4:junit4] 2> 3635 T1390 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard1 [junit4:junit4] 2> 3638 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0003 type:cre= ate cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4067 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4086 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4086 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4098 T1390 oasc.ZkController.register We are http://12= 7.0.0.1:20083/solr/collection1/ and leader is http://127.0.0.1:20083/solr/c= ollection1/ [junit4:junit4] 2> 4098 T1390 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:20083/solr [junit4:junit4] 2> 4099 T1390 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 4100 T1390 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4101 T1390 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 4101 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 4102 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 4263 T1390 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 4266 T1390 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:????? [junit4:junit4] 2> 4267 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4267 T1390 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2-= 1347991813496 [junit4:junit4] 2> 4268 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2= -1347991813496/' [junit4:junit4] 2> 4303 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 4303 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4304 T1390 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2-= 1347991813496 [junit4:junit4] 2> 4304 T1390 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2-1347991813496= /solr.xml [junit4:junit4] 2> 4304 T1390 oasc.CoreContainer. New CoreContainer= 1774977004 [junit4:junit4] 2> 4305 T1390 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2-1347991813496= /' [junit4:junit4] 2> 4305 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2-1347991= 813496/' [junit4:junit4] 2> 4330 T1390 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 4345 T1390 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:20077/solr [junit4:junit4] 2> 4345 T1390 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@e39f6f1 [junit4:junit4] 2> 4346 T1435 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 4346 T1390 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 4347 T1435 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:20077, initiating sessi= on [junit4:junit4] 2> 4347 T1392 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:20087 [junit4:junit4] 2> 4348 T1392 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:20087 [junit4:junit4] 2> 4350 T1394 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139da92ad6c0004 with negotiated timeout 5000 for client /14= 0.211.11.196:20087 [junit4:junit4] 2> 4350 T1435 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:20077, sessio= nid =3D 0x139da92ad6c0004, negotiated timeout =3D 5000 [junit4:junit4] 2> 4350 T1436 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@e39f6f1 name:ZooKeeperConnectio= n Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 4350 T1390 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 4351 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0004 type:cre= ate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4352 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0004 type:cre= ate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4365 T1390 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:20086_solr [junit4:junit4] 2> 4366 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0004 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:20086_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:20086_solr [junit4:junit4] 2> 4367 T1390 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:20086_solr [junit4:junit4] 2> 4369 T1424 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 4369 T1410 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 4369 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 4370 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 4370 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 4370 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 4371 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 4371 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 4372 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 4372 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 4373 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 4373 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 4389 T1390 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 4395 T1390 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2-= 1347991813496/collection1 [junit4:junit4] 2> 4395 T1390 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 4396 T1390 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 4396 T1390 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 4398 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2-1347991= 813496/collection1/' [junit4:junit4] 2> 4399 T1390 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-4.x/solr/build/solr-core/test/J1/solrtest-jetty2-1347991813496/collectio= n1/lib/classes/' to classloader [junit4:junit4] 2> 4400 T1390 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-4.x/solr/build/solr-core/test/J1/solrtest-jetty2-1347991813496/collectio= n1/lib/README' to classloader [junit4:junit4] 2> 4447 T1390 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 4515 T1390 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 4590 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4602 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4602 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4616 T1436 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4616 T1390 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 4626 T1390 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 5103 T1390 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 5111 T1390 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 5113 T1390 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 5122 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5126 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5129 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5130 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5131 T1390 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty2-1347991813496/coll= ection1/, dataDir=3D./org.apache.solr.cloud.BasicDistributedZk2Test-1347991= 809383/jetty2/ [junit4:junit4] 2> 5131 T1390 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@2180b22e [junit4:junit4] 2> 5132 T1390 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347991809383/jetty2/index/ [junit4:junit4] 2> 5132 T1390 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47991809383/jetty2/index' doesn't exist. Creating new index... [junit4:junit4] 2> 5133 T1390 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-4.x/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistribu= tedZk2Test-1347991809383/jetty2/index forceNew:false [junit4:junit4] 2> 5138 T1390 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty2/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 5138 T1390 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 5139 T1390 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 5141 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 5141 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 5141 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 5142 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 5142 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 5142 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 5143 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 5143 T1390 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 5144 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 5144 T1390 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 5145 T1390 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 5145 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 5146 T1390 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 5146 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 5146 T1390 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 5147 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 5147 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5148 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5148 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5149 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5149 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 5150 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5150 T1390 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5151 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 5151 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 5154 T1390 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 5160 T1390 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 5161 T1390 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> 5164 T1390 oass.SolrIndexSearcher. Opening Searc= her@446d853d main [junit4:junit4] 2> 5165 T1390 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 5165 T1390 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 5165 T1390 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 5171 T1390 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 5220 T1437 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@446d853d main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 5608 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5621 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5621 T1436 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5621 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5725 T1390 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 5725 T1390 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:20086/solr shardId:shard2 [junit4:junit4] 2> 5726 T1390 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard2/election [junit4:junit4] 2> 5741 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0004 type:del= ete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode =3D No= Node for /solr/collections/collection1/leaders/shard2 [junit4:junit4] 2> 5741 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 5742 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0004 type:cre= ate cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 5743 T1390 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 5743 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 5744 T1390 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:20086/solr/collection1/ [junit4:junit4] 2> 5744 T1390 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 5744 T1390 oasc.SyncStrategy.syncToMe http://127.0.0.1= :20086/solr/collection1/ has no replicas [junit4:junit4] 2> 5745 T1390 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:20086/solr/collection1/ [junit4:junit4] 2> 5745 T1390 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard2 [junit4:junit4] 2> 5747 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0004 type:cre= ate cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6124 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6139 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6139 T1436 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6139 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6156 T1390 oasc.ZkController.register We are http://12= 7.0.0.1:20086/solr/collection1/ and leader is http://127.0.0.1:20086/solr/c= ollection1/ [junit4:junit4] 2> 6156 T1390 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:20086/solr [junit4:junit4] 2> 6156 T1390 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 6158 T1390 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6158 T1390 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 6159 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 6159 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 6268 T1390 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 6271 T1390 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:????? [junit4:junit4] 2> 6271 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 6271 T1390 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3-= 1347991815549 [junit4:junit4] 2> 6272 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3= -1347991815549/' [junit4:junit4] 2> 6295 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 6295 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 6295 T1390 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3-= 1347991815549 [junit4:junit4] 2> 6296 T1390 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3-1347991815549= /solr.xml [junit4:junit4] 2> 6296 T1390 oasc.CoreContainer. New CoreContainer= 885625531 [junit4:junit4] 2> 6296 T1390 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3-1347991815549= /' [junit4:junit4] 2> 6297 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3-1347991= 815549/' [junit4:junit4] 2> 6313 T1390 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 6323 T1390 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:20077/solr [junit4:junit4] 2> 6324 T1390 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@3c450bb2 [junit4:junit4] 2> 6324 T1447 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 6324 T1390 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 6326 T1447 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:20077, initiating sessi= on [junit4:junit4] 2> 6326 T1392 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:20091 [junit4:junit4] 2> 6326 T1392 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:20091 [junit4:junit4] 2> 6328 T1394 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139da92ad6c0005 with negotiated timeout 5000 for client /14= 0.211.11.196:20091 [junit4:junit4] 2> 6328 T1447 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:20077, sessio= nid =3D 0x139da92ad6c0005, negotiated timeout =3D 5000 [junit4:junit4] 2> 6328 T1448 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@3c450bb2 name:ZooKeeperConnecti= on Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 6328 T1390 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 6329 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0005 type:cre= ate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6343 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0005 type:cre= ate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6344 T1390 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:20090_solr [junit4:junit4] 2> 6344 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0005 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:20090_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:20090_solr [junit4:junit4] 2> 6345 T1390 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:20090_solr [junit4:junit4] 2> 6347 T1410 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6347 T1424 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6347 T1436 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6348 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 6348 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 6348 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 6348 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 6349 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 6349 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 6349 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 6349 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 6350 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 6350 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 6363 T1390 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 6367 T1390 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3-= 1347991815549/collection1 [junit4:junit4] 2> 6367 T1390 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 6368 T1390 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 6368 T1390 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 6369 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3-1347991= 815549/collection1/' [junit4:junit4] 2> 6370 T1390 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-4.x/solr/build/solr-core/test/J1/solrtest-jetty3-1347991815549/collectio= n1/lib/README' to classloader [junit4:junit4] 2> 6370 T1390 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-4.x/solr/build/solr-core/test/J1/solrtest-jetty3-1347991815549/collectio= n1/lib/classes/' to classloader [junit4:junit4] 2> 6403 T1390 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 6449 T1390 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 6550 T1390 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 6554 T1390 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 6652 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6666 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6666 T1448 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6666 T1436 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6666 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6964 T1390 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 6972 T1390 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 6975 T1390 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 6984 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 6988 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 6991 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 6992 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 6993 T1390 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty3-1347991815549/coll= ection1/, dataDir=3D./org.apache.solr.cloud.BasicDistributedZk2Test-1347991= 809383/jetty3/ [junit4:junit4] 2> 6993 T1390 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@2180b22e [junit4:junit4] 2> 6994 T1390 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347991809383/jetty3/index/ [junit4:junit4] 2> 6994 T1390 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47991809383/jetty3/index' doesn't exist. Creating new index... [junit4:junit4] 2> 6995 T1390 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-4.x/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistribu= tedZk2Test-1347991809383/jetty3/index forceNew:false [junit4:junit4] 2> 7000 T1390 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty3/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 7000 T1390 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 7001 T1390 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 7002 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 7003 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 7003 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 7003 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 7004 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 7004 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 7004 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 7005 T1390 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 7005 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 7006 T1390 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 7006 T1390 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 7007 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 7007 T1390 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 7008 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 7008 T1390 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 7008 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 7009 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7009 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7010 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 7010 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 7011 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 7011 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7012 T1390 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7012 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 7013 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 7015 T1390 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 7018 T1390 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 7019 T1390 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> 7022 T1390 oass.SolrIndexSearcher. Opening Searc= her@60c07608 main [junit4:junit4] 2> 7023 T1390 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 7023 T1390 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 7024 T1390 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 7029 T1390 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 7081 T1449 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@60c07608 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 7169 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7179 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7179 T1448 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7179 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7179 T1436 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7586 T1390 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 7586 T1390 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:20090/solr shardId:shard1 [junit4:junit4] 2> 7589 T1390 oasc.ZkController.register We are http://12= 7.0.0.1:20090/solr/collection1/ and leader is http://127.0.0.1:20083/solr/c= ollection1/ [junit4:junit4] 2> 7589 T1390 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:20090/solr [junit4:junit4] 2> 7589 T1390 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 7590 T1390 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C145 name=3Dcollection1 org.apache.sol= r.core.SolrCore@6fecda63 url=3Dhttp://127.0.0.1:20090/solr/collection1 node= =3D127.0.0.1:20090_solr [junit4:junit4] 2> 7591 T1450 C145 P20090 oasc.RecoveryStrategy.run Start= ing recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 7591 T1390 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7591 T1450 C145 P20090 oasc.RecoveryStrategy.doRecover= y ###### startupVersions=3D[] [junit4:junit4] 2> 7592 T1390 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 7592 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 7592 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 7604 T1450 C145 P20090 oasc.RecoveryStrategy.doRecover= y Attempting to PeerSync from http://127.0.0.1:20083/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 7604 T1450 C145 P20090 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:20090/solr START replicas=3D[http:/= /127.0.0.1:20083/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 7605 T1450 C145 P20090 oasu.PeerSync.sync WARNING no f= rame of reference to tell of we've missed updates [junit4:junit4] 2> 7607 T1450 C145 P20090 oasc.RecoveryStrategy.doRecover= y PeerSync Recovery was not successful - trying replication. core=3Dcollect= ion1 [junit4:junit4] 2> 7607 T1450 C145 P20090 oasc.RecoveryStrategy.doRecover= y Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 7607 T1450 C145 P20090 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C146 name=3Dcollection1 org.apache.sol= r.core.SolrCore@3bcf72c7 url=3Dhttp://127.0.0.1:20083/solr/collection1 node= =3D127.0.0.1:20083_solr [junit4:junit4] 2> 7617 T1416 C146 P20083 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 7682 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7695 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7695 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7695 T1448 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7695 T1436 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7744 T1390 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 7747 T1390 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:????? [junit4:junit4] 2> 7747 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 7748 T1390 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1347991816981 [junit4:junit4] 2> 7748 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4= -1347991816981/' [junit4:junit4] 2> 7783 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 7783 T1390 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 7784 T1390 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1347991816981 [junit4:junit4] 2> 7784 T1390 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4-1347991816981= /solr.xml [junit4:junit4] 2> 7784 T1390 oasc.CoreContainer. New CoreContainer= 943509870 [junit4:junit4] 2> 7785 T1390 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4-1347991816981= /' [junit4:junit4] 2> 7785 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4-1347991= 816981/' [junit4:junit4] 2> 7810 T1390 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 7825 T1390 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:20077/solr [junit4:junit4] 2> 7826 T1390 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@320dce4f [junit4:junit4] 2> 7827 T1461 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 7827 T1390 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 7828 T1461 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:20077, initiating sessi= on [junit4:junit4] 2> 7828 T1392 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:20095 [junit4:junit4] 2> 7828 T1392 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:20095 [junit4:junit4] 2> 7830 T1394 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139da92ad6c0006 with negotiated timeout 5000 for client /14= 0.211.11.196:20095 [junit4:junit4] 2> 7830 T1461 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:20077, sessio= nid =3D 0x139da92ad6c0006, negotiated timeout =3D 5000 [junit4:junit4] 2> 7830 T1462 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@320dce4f name:ZooKeeperConnecti= on Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 7830 T1390 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 7831 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0006 type:cre= ate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 7832 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0006 type:cre= ate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 7834 T1390 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:20094_solr [junit4:junit4] 2> 7835 T1395 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139da92ad6c0006 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:20094_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:20094_solr [junit4:junit4] 2> 7842 T1390 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:20094_solr [junit4:junit4] 2> 7844 T1410 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7844 T1448 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7844 T1436 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7844 T1424 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7845 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 7845 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 7846 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 7846 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 7847 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 7847 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 7847 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 7848 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 7848 T1390 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 7849 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 7865 T1390 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 7871 T1390 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1347991816981/collection1 [junit4:junit4] 2> 7872 T1390 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 7872 T1390 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 7873 T1390 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 7874 T1390 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4-1347991= 816981/collection1/' [junit4:junit4] 2> 7876 T1390 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-4.x/solr/build/solr-core/test/J1/solrtest-jetty4-1347991816981/collectio= n1/lib/README' to classloader [junit4:junit4] 2> 7876 T1390 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-4.x/solr/build/solr-core/test/J1/solrtest-jetty4-1347991816981/collectio= n1/lib/classes/' to classloader [junit4:junit4] 2> 7928 T1390 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 8000 T1390 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 8101 T1390 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 8108 T1390 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 8738 T1390 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 8749 T1390 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 8754 T1390 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 8764 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 8769 T1390 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 8774 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 8775 T1390 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 8776 T1390 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-4.x/solr/build/solr-core/test/J1/./solrtest-jetty4-1347991816981/coll= ection1/, dataDir=3D./org.apache.solr.cloud.BasicDistributedZk2Test-1347991= 809383/jetty4/ [junit4:junit4] 2> 8776 T1390 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@2180b22e [junit4:junit4] 2> 8777 T1390 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347991809383/jetty4/index/ [junit4:junit4] 2> 8778 T1390 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47991809383/jetty4/index' doesn't exist. Creating new index... [junit4:junit4] 2> 8779 T1390 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-4.x/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistribu= tedZk2Test-1347991809383/jetty4/index forceNew:false [junit4:junit4] 2> 8789 T1390 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty4/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 8790 T1390 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 8791 T1390 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 8793 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 8794 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 8794 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 8795 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 8796 T1390 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 8796 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 8797 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 8797 T1390 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 8798 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 8799 T1390 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 8800 T1390 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 8800 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 8801 T1390 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 8801 T1390 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 8802 T1390 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 8803 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 8803 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8804 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8805 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 8806 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 8806 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 8807 T1390 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8808 T1390 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8808 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 8809 T1390 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 8813 T1390 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 8818 T1390 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 8819 T1390 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> 8823 T1390 oass.SolrIndexSearcher. Opening Searc= her@6279c67f main [junit4:junit4] 2> 8824 T1390 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 8825 T1390 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 8825 T1390 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 8833 T1390 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 8887 T1463 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@6279c67f main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 9203 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9216 T1462 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9216 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9216 T1436 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9216 T1448 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9216 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9395 T1390 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 9395 T1390 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:20094/solr shardId:shard2 [junit4:junit4] 2> 9398 T1390 oasc.ZkController.register We are http://12= 7.0.0.1:20094/solr/collection1/ and leader is http://127.0.0.1:20086/solr/c= ollection1/ [junit4:junit4] 2> 9399 T1390 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:20094/solr [junit4:junit4] 2> 9400 T1390 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 9400 T1390 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C147 name=3Dcollection1 org.apache.sol= r.core.SolrCore@529b9757 url=3Dhttp://127.0.0.1:20094/solr/collection1 node= =3D127.0.0.1:20094_solr [junit4:junit4] 2> 9401 T1464 C147 P20094 oasc.RecoveryStrategy.run Start= ing recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 9402 T1464 C147 P20094 oasc.RecoveryStrategy.doRecover= y ###### startupVersions=3D[] [junit4:junit4] 2> 9401 T1390 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9403 T1464 C147 P20094 oasc.RecoveryStrategy.doRecover= y Attempting to PeerSync from http://127.0.0.1:20086/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 9403 T1464 C147 P20094 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:20094/solr START replicas=3D[http:/= /127.0.0.1:20086/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 9403 T1390 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 9403 T1464 C147 P20094 oasu.PeerSync.sync WARNING no f= rame of reference to tell of we've missed updates [junit4:junit4] 2> 9404 T1464 C147 P20094 oasc.RecoveryStrategy.doRecover= y PeerSync Recovery was not successful - trying replication. core=3Dcollect= ion1 [junit4:junit4] 2> 9404 T1390 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 9404 T1464 C147 P20094 oasc.RecoveryStrategy.doRecover= y Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 9405 T1464 C147 P20094 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 9405 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C148 name=3Dcollection1 org.apache.sol= r.core.SolrCore@53c8fbc5 url=3Dhttp://127.0.0.1:20086/solr/collection1 node= =3D127.0.0.1:20086_solr [junit4:junit4] 2> 9417 T1428 C148 P20086 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 9422 T1390 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@7927fcde [junit4:junit4] 2> 9423 T1466 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 9423 T1390 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 9424 T1466 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:20077, initiating sessi= on [junit4:junit4] 2> 9424 T1392 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:20098 [junit4:junit4] 2> 9424 T1392 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:20098 [junit4:junit4] 2> 9425 T1394 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139da92ad6c0007 with negotiated timeout 10000 for client /1= 40.211.11.196:20098 [junit4:junit4] 2> 9425 T1466 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:20077, sessio= nid =3D 0x139da92ad6c0007, negotiated timeout =3D 10000 [junit4:junit4] 2> 9426 T1467 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@7927fcde name:ZooKeeperConnecti= on Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 9426 T1390 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 9426 T1390 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 9428 T1390 oasc.ChaosMonkey.monkeyLog monkey: init - e= xpire sessions:true cause connection loss:true [junit4:junit4] 2> 9428 T1390 oascsi.HttpClientUtil.createClient Creating= new http client, config: [junit4:junit4] 2> 9438 T1390 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> ASYNC NEW_CORE C149 name=3Dcollection1 org.apache.sol= r.core.SolrCore@1c2ba649 url=3Dhttp://127.0.0.1:20080/solr/collection1 node= =3D127.0.0.1:20080_solr [junit4:junit4] 2> 9448 T1402 C149 P20080 oasc.SolrDeletionPolicy.onInit = SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/control/data/index,segFN=3D= segments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 9449 T1402 C149 P20080 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 9452 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[1 (1413471869416243200)]} 0 8 [junit4:junit4] 2> 9453 T1390 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@318603db [junit4:junit4] 2> 9454 T1468 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 9454 T1390 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 9455 T1468 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:20077, initiating sessi= on [junit4:junit4] 2> 9455 T1392 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:20100 [junit4:junit4] 2> 9455 T1392 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:20100 [junit4:junit4] 2> 9457 T1394 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139da92ad6c0008 with negotiated timeout 10000 for client /1= 40.211.11.196:20100 [junit4:junit4] 2> 9457 T1468 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:20077, sessio= nid =3D 0x139da92ad6c0008, negotiated timeout =3D 10000 [junit4:junit4] 2> 9457 T1469 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@318603db name:ZooKeeperConnecti= on Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 9457 T1390 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 9458 T1390 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 9465 T1418 C146 P20083 oasc.SolrDeletionPolicy.onInit = SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty1/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 9466 T1418 C146 P20083 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> C145_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:20090_solr, base_url=3Dhttp://1= 27.0.0.1:20090/solr} [junit4:junit4] 2> 9482 T1440 C145 P20090 oasc.SolrDeletionPolicy.onInit = SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty3/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 9483 T1440 C145 P20090 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 9488 T1440 C145 P20090 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djava= bin&version=3D2} status=3D0 QTime=3D10=20 [junit4:junit4] 2> 9489 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[1 (1413471869433020416)]} 0 28 [junit4:junit4] 2> 9490 T1390 oasc.AbstractDistribZkTestBase.waitForRecov= eriesToFinish Wait for recoveries to finish - collection: collection1 failO= nTimeout:true timeout (sec):3600 [junit4:junit4] 2> 9490 T1390 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9720 T1411 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9733 T1448 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9733 T1424 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9733 T1410 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9733 T1436 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9733 T1462 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9733 T1467 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9733 T1469 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 10492 T1390 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10620 T1450 C145 P20090 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 10620 T1450 C145 P20090 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dtlog{file=3D.= /org.apache.solr.cloud.BasicDistributedZk2Test-1347991809383/jetty3/tlog/tl= og.0000000000000000000 refcount=3D1}} [junit4:junit4] 2> 10620 T1450 C145 P20090 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:20083/solr/collection1/. co= re=3Dcollection1 [junit4:junit4] 2> 10620 T1450 C145 P20090 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 10628 T1419 C146 P20083 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 10740 T1419 C146 P20083 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty1/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty1/index,segFN=3Dsegmen= ts_2,generation=3D2,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_MockVari= ableIntBlock_0.skp, _0_Pulsing40_0.prx, _0_Pulsing40_0.frq, _0_MockVariable= IntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _0_MockVariableIntBlock_0.po= s, _0_Pulsing40_0.tip, _0.si, _0_MockVariableIntBlock_0.tii, _0.fnm, _0_Pul= sing40_0.tim, _0_Memory_0.ram, _0_nrm.cfs, _0_MockRandom_0.skp, _0_MockRand= om_0.tip, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.doc, _0_= MockRandom_0.pos, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, segments_2, _0= .fdx, _0_MockRandom_0.pyl, _0_MockRandom_0.tim, _0.fdt] [junit4:junit4] 2> 10741 T1419 C146 P20083 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 10756 T1419 C146 P20083 oass.SolrIndexSearcher. = Opening Searcher@5ad52fed main [junit4:junit4] 2> 10757 T1419 C146 P20083 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 10758 T1425 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@5ad52fed main{StandardDirectoryReader(s= egments_2:3 _0(4.0.0.2):C1)} [junit4:junit4] 2> 10758 T1419 C146 P20083 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 130 [junit4:junit4] 2> 10759 T1450 C145 P20090 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 10759 T1450 C145 P20090 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 10762 T1420 C146 P20083 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 10762 T1420 C146 P20083 REQ /replication {command=3Din= dexversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 10763 T1450 C145 P20090 oash.SnapPuller.fetchLatestInd= ex Master's generation: 2 [junit4:junit4] 2> 10763 T1450 C145 P20090 oash.SnapPuller.fetchLatestInd= ex Slave's generation: 1 [junit4:junit4] 2> 10763 T1450 C145 P20090 oash.SnapPuller.fetchLatestInd= ex Starting replication process [junit4:junit4] 2> 10768 T1420 C146 P20083 REQ /replication {command=3Dfi= lelist&generation=3D2&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D= 0 QTime=3D3=20 [junit4:junit4] 2> 10769 T1450 C145 P20090 oash.SnapPuller.fetchLatestInd= ex Number of files in latest index in master: 27 [junit4:junit4] 2> 10777 T1450 C145 P20090 oash.SnapPuller.fetchLatestInd= ex Starting download to ./org.apache.solr.cloud.BasicDistributedZk2Test-134= 7991809383/jetty3/index.20120918141020150 fullCopy=3Dtrue [junit4:junit4] 2> 10790 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckVariableIntBlock_0.doc&command=3Dfilecontent&checksum=3Dtrue&generation= =3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 10802 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckVariableIntBlock_0.skp&command=3Dfilecontent&checksum=3Dtrue&generation= =3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10804 T1450 C145 P20090 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockVariableInt= Block_0.skp, lastmodified=3D1347991820000, size=3D0} [junit4:junit4] 2> 10807 T1420 C146 P20083 REQ /replication {file=3D_0_Pu= lsing40_0.prx&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10811 T1420 C146 P20083 REQ /replication {file=3D_0_Pu= lsing40_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10815 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckVariableIntBlock_0.pyl&command=3Dfilecontent&checksum=3Dtrue&generation= =3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10816 T1450 C145 P20090 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockVariableInt= Block_0.pyl, lastmodified=3D1347991820000, size=3D0} [junit4:junit4] 2> 10820 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckVariableIntBlock_0.tib&command=3Dfilecontent&checksum=3Dtrue&generation= =3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10824 T1420 C146 P20083 REQ /replication {file=3D_0_Pu= lsing40_0.tip&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10828 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckVariableIntBlock_0.pos&command=3Dfilecontent&checksum=3Dtrue&generation= =3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10832 T1420 C146 P20083 REQ /replication {file=3D_0.si= &command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replication&wt= =3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10836 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckVariableIntBlock_0.tii&command=3Dfilecontent&checksum=3Dtrue&generation= =3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10840 T1420 C146 P20083 REQ /replication {file=3D_0_Pu= lsing40_0.tim&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10844 T1420 C146 P20083 REQ /replication {file=3D_0.fn= m&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replication&wt= =3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10848 T1420 C146 P20083 REQ /replication {file=3D_0_Me= mory_0.ram&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repli= cation&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10852 T1420 C146 P20083 REQ /replication {file=3D_0_nr= m.cfs&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replicatio= n&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10856 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckRandom_0.skp&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10857 T1450 C145 P20090 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockRandom_0.sk= p, lastmodified=3D1347991820000, size=3D0} [junit4:junit4] 2> 10861 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckRandom_0.sd&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10865 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckRandom_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10869 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckRandom_0.tip&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10873 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckRandom_0.doc&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10877 T1420 C146 P20083 REQ /replication {file=3D_0_nr= m.cfe&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replicatio= n&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10881 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckVariableIntBlock_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation= =3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10885 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckRandom_0.pos&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10889 T1420 C146 P20083 REQ /replication {file=3Dsegme= nts_2&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replicatio= n&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10893 T1420 C146 P20083 REQ /replication {file=3D_0.fd= x&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replication&wt= =3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10897 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckRandom_0.pyl&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10898 T1450 C145 P20090 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockRandom_0.py= l, lastmodified=3D1347991820000, size=3D0} [junit4:junit4] 2> 10902 T1420 C146 P20083 REQ /replication {file=3D_0_Mo= ckRandom_0.tim&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10906 T1420 C146 P20083 REQ /replication {file=3D_0.fd= t&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replication&wt= =3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10907 T1450 C145 P20090 oash.SnapPuller.fetchLatestInd= ex Total time taken for download : 0 secs [junit4:junit4] 2> 10908 T1450 C145 P20090 oash.SnapPuller.modifyIndexPro= ps New index installed. Updating index properties... index=3Dindex.20120918= 141020150 [junit4:junit4] 2> 10919 T1450 C145 P20090 oasu.DefaultSolrCoreState.newI= ndexWriter Creating new IndexWriter... [junit4:junit4] 2> 10920 T1450 C145 P20090 oasu.DefaultSolrCoreState.newI= ndexWriter Waiting until IndexWriter is unused... core=3Dcollection1 [junit4:junit4] 2> 10920 T1450 C145 P20090 oasu.DefaultSolrCoreState.newI= ndexWriter Rollback old IndexWriter... core=3Dcollection1 [junit4:junit4] 2> 10925 T1450 C145 P20090 oasc.SolrCore.getNewIndexDir W= ARNING New index directory detected: old=3D./org.apache.solr.cloud.BasicDis= tributedZk2Test-1347991809383/jetty3/index/ new=3D./org.apache.solr.cloud.B= asicDistributedZk2Test-1347991809383/jetty3/index.20120918141020150 [junit4:junit4] 2> 10926 T1450 C145 P20090 oasc.CachingDirectoryFactory.g= et return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/./org.apache.solr.cloud.= BasicDistributedZk2Test-1347991809383/jetty3/index.20120918141020150 forceN= ew:true [junit4:junit4] 2> 10938 T1450 C145 P20090 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty3/index.20120918141020= 150,segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_MockVariableIntBlock_= 0.doc, _0_MockVariableIntBlock_0.skp, _0_Pulsing40_0.prx, _0_Pulsing40_0.fr= q, _0_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _0_Pulsing= 40_0.tip, _0_MockVariableIntBlock_0.pos, _0.si, _0_MockVariableIntBlock_0.t= ii, _0_Pulsing40_0.tim, _0.fnm, _0_Memory_0.ram, _0_nrm.cfs, _0_MockRandom_= 0.skp, _0_MockRandom_0.tip, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_Moc= kRandom_0.doc, _0_MockRandom_0.pos, _0_MockVariableIntBlock_0.frq, _0_nrm.c= fe, segments_2, _0.fdx, _0_MockRandom_0.pyl, _0_MockRandom_0.tim, _0.fdt] [junit4:junit4] 2> 10938 T1450 C145 P20090 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 10938 T1450 C145 P20090 oasu.DefaultSolrCoreState.newI= ndexWriter New IndexWriter is ready to be used. [junit4:junit4] 2> 10943 T1450 C145 P20090 oass.SolrIndexSearcher. = Opening Searcher@2d893cba main [junit4:junit4] 2> 10945 T1449 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@2d893cba main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C1)} [junit4:junit4] 2> 10945 T1449 oasc.CachingDirectoryFactory.close Closing= directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests= -4.x/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZ= k2Test-1347991809383/jetty3/index [junit4:junit4] 2> 10946 T1449 oash.SnapPuller$2.onClose removing old ind= ex directory ./org.apache.solr.cloud.BasicDistributedZk2Test-1347991809383/= jetty3/index [junit4:junit4] 2> 10947 T1450 C145 P20090 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dfal= se,waitSearcher=3Dfalse,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 10948 T1450 C145 P20090 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 10948 T1450 C145 P20090 oasc.RecoveryStrategy.replay R= eplaying buffered documents. core=3Dcollection1 [junit4:junit4] 2> 10948 T1472 C145 P20090 oasu.UpdateLog$LogReplayer.doR= eplay WARNING Starting log replay tlog{file=3D./org.apache.solr.cloud.Basic= DistributedZk2Test-1347991809383/jetty3/tlog/tlog.0000000000000000000 refco= unt=3D2} active=3Dtrue starting pos=3D432 [junit4:junit4] 2> 10949 T1472 C145 P20090 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D2,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 10950 T1472 C145 P20090 oass.SolrIndexSearcher. = Opening Searcher@1940d1f6 main [junit4:junit4] 2> 10950 T1472 C145 P20090 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 10952 T1449 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1940d1f6 main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C1)} [junit4:junit4] 2> 10952 T1472 C145 P20090 oasu.UpdateLog$LogReplayer.run= WARNING Log replay finished. recoveryInfo=3DRecoveryInfo{adds=3D0 deletes= =3D0 deleteByQuery=3D0 errors=3D0 positionOfStart=3D432} [junit4:junit4] 2> 10952 T1450 C145 P20090 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 10954 T1450 C145 P20090 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 11239 T1411 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11251 T1448 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11251 T1424 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11251 T1467 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11251 T1469 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11251 T1436 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11251 T1410 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11251 T1462 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11494 T1390 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> C147_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard2, roles=3Dnull, state=3Drecovering, core=3Dcollection1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:20094_solr, base_url=3Dhttp://1= 27.0.0.1:20094/solr} [junit4:junit4] 2> 12425 T1464 C147 P20094 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 12425 T1464 C147 P20094 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 12425 T1464 C147 P20094 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:20086/solr/collection1/. co= re=3Dcollection1 [junit4:junit4] 2> 12425 T1464 C147 P20094 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 12441 T1430 C148 P20086 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 12444 T1430 C148 P20086 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty2/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 12445 T1430 C148 P20086 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> 12446 T1430 C148 P20086 oass.SolrIndexSearcher. = Opening Searcher@3e8bb77b main [junit4:junit4] 2> 12446 T1430 C148 P20086 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 12447 T1437 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@3e8bb77b main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 12448 T1430 C148 P20086 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 7 [junit4:junit4] 2> 12449 T1464 C147 P20094 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 12449 T1464 C147 P20094 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 12450 T1431 C148 P20086 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 12451 T1431 C148 P20086 REQ /replication {command=3Din= dexversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 12455 T1464 C147 P20094 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty4/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 12455 T1464 C147 P20094 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> 12456 T1464 C147 P20094 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 12459 T1464 C147 P20094 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty4/index,segFN=3Dsegmen= ts_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/jetty4/index,segFN=3Dsegmen= ts_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 12459 T1464 C147 P20094 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 12463 T1464 C147 P20094 oass.SolrIndexSearcher. = Opening Searcher@ef99e17 main [junit4:junit4] 2> 12463 T1464 C147 P20094 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 12464 T1463 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@ef99e17 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 12465 T1464 C147 P20094 oasc.RecoveryStrategy.replay N= o replay needed. core=3Dcollection1 [junit4:junit4] 2> 12465 T1464 C147 P20094 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 12471 T1464 C147 P20094 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 12496 T1390 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12758 T1411 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12762 T1448 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12762 T1462 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12762 T1467 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12762 T1469 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12762 T1436 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12762 T1424 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12762 T1410 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13498 T1390 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13501 T1402 C149 P20080 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 13587 T1402 C149 P20080 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/control/data/index,segFN=3D= segments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/control/data/index,segFN=3D= segments_2,generation=3D2,filenames=3D[_0_MockRandom_0.tib, _0.fnm, _0_Puls= ing40_0.tim, _0_Memory_0.ram, _0_MockVariableIntBlock_0.doc, _0_MockRandom_= 0.prx, _0_nrm.cfs, _0_MockVariableIntBlock_0.skp, _0_Pulsing40_0.prx, _0_Pu= lsing40_0.frq, _0_MockVariableIntBlock_0.pyl, _0_MockRandom_0.frq, _0_MockR= andom_0.sd, _0_MockVariableIntBlock_0.tib, _0_MockVariableIntBlock_0.frq, _= 0_nrm.cfe, _0_Pulsing40_0.tip, _0_MockRandom_0.tii, _0_MockVariableIntBlock= _0.pos, segments_2, _0.fdx, _0.si, _0.fdt, _0_MockVariableIntBlock_0.tii] [junit4:junit4] 2> 13587 T1402 C149 P20080 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 13598 T1402 C149 P20080 oass.SolrIndexSearcher. = Opening Searcher@203e4015 main [junit4:junit4] 2> 13599 T1402 C149 P20080 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 13600 T1413 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@203e4015 main{StandardDirectoryReader(s= egments_2:3 _0(4.0.0.2):C1)} [junit4:junit4] 2> 13600 T1402 C149 P20080 /update {waitSearcher=3Dtrue&w= t=3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 99 [junit4:junit4] 2> 13602 T1432 C148 P20086 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 13605 T1432 C148 P20086 oass.SolrIndexSearcher. = Opening Searcher@1a50cabc main [junit4:junit4] 2> 13605 T1432 C148 P20086 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 13607 T1437 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1a50cabc main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 13607 T1432 C148 P20086 oasu.SolrCmdDistributor.distri= bCommit Distrib commit to:[StdNode: http://127.0.0.1:20083/solr/collection1= /, StdNode: http://127.0.0.1:20090/solr/collection1/, StdNode: http://127.0= .0.1:20094/solr/collection1/] [junit4:junit4] 2> 13609 T1421 C146 P20083 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> C145_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collecti= on=3Dcollection1, node_name=3D127.0.0.1:20090_solr, base_url=3Dhttp://127.0= .0.1:20090/solr} [junit4:junit4] 2> 13612 T1440 C145 P20090 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 13614 T1440 C145 P20090 oass.SolrIndexSearcher. = Opening Searcher@431e34c7 main [junit4:junit4] 2> 13615 T1440 C145 P20090 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 13617 T1449 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@431e34c7 main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C1)} [junit4:junit4] 2> 13617 T1440 C145 P20090 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&so= ftCommit=3Dfalse&version=3D2} {commit=3D} 0 6 [junit4:junit4] 2> 13620 T1421 C146 P20083 oass.SolrIndexSearcher. = Opening Searcher@1e92e158 main [junit4:junit4] 2> 13621 T1421 C146 P20083 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 13626 T1425 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1e92e158 main{StandardDirectoryReader(s= egments_2:3 _0(4.0.0.2):C1)} [junit4:junit4] 2> C147_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard2, roles=3Dnull, state=3Dactive, core=3Dcollection1, collecti= on=3Dcollection1, node_name=3D127.0.0.1:20094_solr, base_url=3Dhttp://127.0= .0.1:20094/solr} [junit4:junit4] 2> 13626 T1454 C147 P20094 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 13627 T1421 C146 P20083 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&so= ftCommit=3Dfalse&version=3D2} {commit=3D} 0 18 [junit4:junit4] 2> 13630 T1454 C147 P20094 oass.SolrIndexSearcher. = Opening Searcher@59efd601 main [junit4:junit4] 2> 13630 T1454 C147 P20094 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 13632 T1463 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@59efd601 main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 13632 T1454 C147 P20094 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&so= ftCommit=3Dfalse&version=3D2} {commit=3D} 0 6 [junit4:junit4] 2> 13633 T1432 C148 P20086 /update {waitSearcher=3Dtrue&w= t=3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 31 [junit4:junit4] 2> 13638 T1403 C149 P20080 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:20080/solr/collection1/&NOW=3D1347991823015&start=3D0&q= =3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&fsv=3Dtrue&rows=3D10&ver= sion=3D2} hits=3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13641 T1403 C149 P20080 REQ /select {shard.url=3D127.0= .0.1:20080/solr/collection1/&NOW=3D1347991823015&q=3D*:*&ids=3D1&distrib=3D= false&isShard=3Dtrue&wt=3Djavabin&version=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13642 T1402 C149 P20080 REQ /select {wt=3Djavabin&q=3D= *:*&version=3D2} hits=3D1 status=3D0 QTime=3D7=20 [junit4:junit4] 2> 13643 T1390 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:20077/solr sessionTimeout=3D10000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@50b58f5 [junit4:junit4] 2> 13644 T1477 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:20077 [junit4:junit4] 2> 13644 T1390 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 13644 T1477 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost/127.0.0.1:20077, initiating sess= ion [junit4:junit4] 2> 13644 T1392 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /140.211.11.196:20113 [junit4:junit4] 2> 13644 T1392 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /140.211.11.196:20113 [junit4:junit4] 2> 13646 T1394 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x139da92ad6c0009 with negotiated timeout 10000 for client /= 140.211.11.196:20113 [junit4:junit4] 2> 13646 T1477 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server localhost/127.0.0.1:20077, sessi= onid =3D 0x139da92ad6c0009, negotiated timeout =3D 10000 [junit4:junit4] 2> 13647 T1478 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@50b58f5 name:ZooKeeperConnecti= on Watcher:127.0.0.1:20077/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 13647 T1390 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 13648 T1390 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 13649 T1395 oazs.PrepRequestProcessor.pRequest Process= ed session termination for sessionid: 0x139da92ad6c0009 [junit4:junit4] 2> 13657 T1478 oaz.ClientCnxn$EventThread.run EventThread= shut down [junit4:junit4] 2> 13657 T1392 oazs.NIOServerCnxn.closeSock Closed socket= connection for client /140.211.11.196:20113 which had sessionid 0x139da92a= d6c0009 [junit4:junit4] 2> 13657 T1390 oaz.ZooKeeper.close Session: 0x139da92ad6c= 0009 closed [junit4:junit4] 2> 13659 T1422 C146 P20083 REQ /select {distrib=3Dfalse&w= t=3Djavabin&q=3D*:*&version=3D2} hits=3D1 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 13661 T1433 C148 P20086 REQ /select {distrib=3Dfalse&w= t=3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 13665 T1417 C146 P20083 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:20083/solr/collection1/|127.0.0.1:20090/solr/collection1= /&NOW=3D1347991823042&start=3D0&q=3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt= =3Djavabin&fsv=3Dtrue&rows=3D10&version=3D2} hits=3D1 status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 13665 T1456 C147 P20094 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:20086/solr/collection1/|127.0.0.1:20094/solr/collection1= /&NOW=3D1347991823042&start=3D0&q=3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt= =3Djavabin&fsv=3Dtrue&rows=3D10&version=3D2} hits=3D0 status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 13668 T1417 C146 P20083 REQ /select {shard.url=3D127.0= .0.1:20083/solr/collection1/|127.0.0.1:20090/solr/collection1/&NOW=3D134799= 1823042&q=3D*:*&ids=3D1&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&version= =3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13669 T1455 C147 P20094 REQ /select {wt=3Djavabin&q=3D= *:*&version=3D2} hits=3D1 status=3D0 QTime=3D7=20 [junit4:junit4] 2> 13675 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[2 (1413471873848573952)]} 0 3 [junit4:junit4] 2> 13690 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 13690 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[2 (1413471873855913984)]} 0 12 [junit4:junit4] 2> 13695 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[3 (1413471873870594048)]} 0 2 [junit4:junit4] 2> 13703 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13703 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[3 (1413471873875836928)]} 0 6 [junit4:junit4] 2> 13707 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[4 (1413471873884225536)]} 0 1 [junit4:junit4] 2> 13722 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 13722 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[4 (1413471873889468416)]} 0 12 [junit4:junit4] 2> 13727 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[5 (1413471873904148480)]} 0 2 [junit4:junit4] 2> 13736 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13736 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[5 (1413471873909391360)]} 0 7 [junit4:junit4] 2> 13740 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[6 (1413471873918828544)]} 0 1 [junit4:junit4] 2> 13749 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13750 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[6 (1413471873924071424)]} 0 7 [junit4:junit4] 2> 13755 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[7 (1413471873933508608)]} 0 2 [junit4:junit4] 2> 13766 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13766 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 13767 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[7]} 0 10 [junit4:junit4] 2> 13772 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[8 (1413471873951334400)]} 0 2 [junit4:junit4] 2> 13782 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 13782 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[8 (1413471873956577280)]} 0 8 [junit4:junit4] 2> 13787 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[9 (1413471873968111616)]} 0 2 [junit4:junit4] 2> 13797 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13798 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[9 (1413471873973354496)]} 0 8 [junit4:junit4] 2> 13803 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[10 (1413471873983840256)]} 0 2 [junit4:junit4] 2> 13815 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 13815 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 13816 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[10]} 0 11 [junit4:junit4] 2> 13849 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[11 (1413471874003763200)]} 0 30 [junit4:junit4] 2> 13859 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13860 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[11 (1413471874038366208)]} 0 8 [junit4:junit4] 2> 13866 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[12 (1413471874048851968)]} 0 3 [junit4:junit4] 2> 13879 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 13879 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 13880 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[12]} 0 11 [junit4:junit4] 2> 13885 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[13 (1413471874069823488)]} 0 2 [junit4:junit4] 2> 13897 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 13898 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D8=20 [junit4:junit4] 2> 13898 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[13]} 0 11 [junit4:junit4] 2> 13903 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[14 (1413471874088697856)]} 0 2 [junit4:junit4] 2> 13911 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13912 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[14 (1413471874093940736)]} 0 7 [junit4:junit4] 2> 13916 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[15 (1413471874103377920)]} 0 1 [junit4:junit4] 2> 13925 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13926 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[15 (1413471874108620800)]} 0 7 [junit4:junit4] 2> 13929 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[16 (1413471874117009408)]} 0 1 [junit4:junit4] 2> 13941 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 13942 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 13943 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[16]} 0 11 [junit4:junit4] 2> 13950 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[17 (1413471874136932352)]} 0 4 [junit4:junit4] 2> 13968 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 13968 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[17 (1413471874146369536)]} 0 15 [junit4:junit4] 2> 13974 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[100 (1413471874164195328)]} 0 2 [junit4:junit4] 2> 14037 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D27=20 [junit4:junit4] 2> 14038 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[100 (1413471874168389632)]} 0 61 [junit4:junit4] 2> 14044 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[101 (1413471874236547072)]} 0 3 [junit4:junit4] 2> 14056 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14056 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[101 (1413471874241789952)]} 0 10 [junit4:junit4] 2> 14062 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[102 (1413471874256470016)]} 0 2 [junit4:junit4] 2> 14072 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14073 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[102 (1413471874261712896)]} 0 8 [junit4:junit4] 2> 14104 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[103 (1413471874273247232)]} 0 28 [junit4:junit4] 2> 14116 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14117 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 14118 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[103]} 0 11 [junit4:junit4] 2> 14125 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[104 (1413471874320433152)]} 0 4 [junit4:junit4] 2> 14134 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14135 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[104 (1413471874326724608)]} 0 7 [junit4:junit4] 2> 14140 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[105 (1413471874337210368)]} 0 2 [junit4:junit4] 2> 14205 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D29=20 [junit4:junit4] 2> 14206 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D60=20 [junit4:junit4] 2> 14206 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[105]} 0 63 [junit4:junit4] 2> 14211 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[106 (1413471874412707840)]} 0 2 [junit4:junit4] 2> 14228 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 14229 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D11=20 [junit4:junit4] 2> 14230 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[106]} 0 15 [junit4:junit4] 2> 14235 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[107 (1413471874437873664)]} 0 2 [junit4:junit4] 2> 14247 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14248 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 14248 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[107]} 0 10 [junit4:junit4] 2> 14254 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[108 (1413471874456748032)]} 0 2 [junit4:junit4] 2> 14266 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14267 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 14267 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[108]} 0 10 [junit4:junit4] 2> 14272 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[109 (1413471874475622400)]} 0 2 [junit4:junit4] 2> 14284 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14285 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 14285 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[109]} 0 10 [junit4:junit4] 2> 14290 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[110 (1413471874494496768)]} 0 2 [junit4:junit4] 2> 14303 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14304 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D8=20 [junit4:junit4] 2> 14304 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[110]} 0 11 [junit4:junit4] 2> 14309 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[111 (1413471874514419712)]} 0 2 [junit4:junit4] 2> 14318 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14318 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[111 (1413471874519662592)]} 0 6 [junit4:junit4] 2> 14323 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[112 (1413471874530148352)]} 0 2 [junit4:junit4] 2> 14332 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14332 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[112 (1413471874534342656)]} 0 6 [junit4:junit4] 2> 14358 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[113 (1413471874544828416)]} 0 23 [junit4:junit4] 2> 14367 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14368 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[113 (1413471874572091392)]} 0 6 [junit4:junit4] 2> 14372 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[114 (1413471874580480000)]} 0 2 [junit4:junit4] 2> 14379 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14380 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[114 (1413471874585722880)]} 0 5 [junit4:junit4] 2> 14384 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[115 (1413471874594111488)]} 0 1 [junit4:junit4] 2> 14391 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14392 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[115 (1413471874598305792)]} 0 5 [junit4:junit4] 2> 14395 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[116 (1413471874605645824)]} 0 1 [junit4:junit4] 2> 14406 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14407 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 14408 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[116]} 0 10 [junit4:junit4] 2> 14411 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[117 (1413471874622423040)]} 0 1 [junit4:junit4] 2> 14421 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14422 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 14422 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[117]} 0 8 [junit4:junit4] 2> 14427 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[118 (1413471874639200256)]} 0 2 [junit4:junit4] 2> 14459 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D14=20 [junit4:junit4] 2> 14460 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[118 (1413471874642345984)]} 0 31 [junit4:junit4] 2> 14464 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[119 (1413471874677997568)]} 0 1 [junit4:junit4] 2> 14471 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14471 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[119 (1413471874682191872)]} 0 4 [junit4:junit4] 2> 14475 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[120 (1413471874689531904)]} 0 1 [junit4:junit4] 2> 14485 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14486 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14486 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[120]} 0 8 [junit4:junit4] 2> 14490 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[121 (1413471874705260544)]} 0 1 [junit4:junit4] 2> 14500 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14501 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 14501 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[121]} 0 8 [junit4:junit4] 2> 14505 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[122 (1413471874720989184)]} 0 1 [junit4:junit4] 2> 14514 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14514 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[122 (1413471874725183488)]} 0 6 [junit4:junit4] 2> 14532 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[123 (1413471874734620672)]} 0 15 [junit4:junit4] 2> 14542 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14543 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 14543 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[123]} 0 9 [junit4:junit4] 2> 14548 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[124 (1413471874765029376)]} 0 2 [junit4:junit4] 2> 14555 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14556 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[124 (1413471874770272256)]} 0 5 [junit4:junit4] 2> 14559 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[125 (1413471874777612288)]} 0 1 [junit4:junit4] 2> 14567 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14568 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[125 (1413471874781806592)]} 0 6 [junit4:junit4] 2> 14571 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[126 (1413471874790195200)]} 0 1 [junit4:junit4] 2> 14579 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14579 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14580 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[126]} 0 7 [junit4:junit4] 2> 14584 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[127 (1413471874803826688)]} 0 1 [junit4:junit4] 2> 14618 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D15=20 [junit4:junit4] 2> 14619 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[127 (1413471874808020992)]} 0 32 [junit4:junit4] 2> 14623 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[128 (1413471874844721152)]} 0 1 [junit4:junit4] 2> 14633 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14633 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[128 (1413471874848915456)]} 0 7 [junit4:junit4] 2> 14637 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[129 (1413471874859401216)]} 0 1 [junit4:junit4] 2> 14646 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14647 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 14647 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[129]} 0 7 [junit4:junit4] 2> 14651 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[130 (1413471874874081280)]} 0 1 [junit4:junit4] 2> 14659 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14659 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14660 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[130]} 0 7 [junit4:junit4] 2> 14664 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[131 (1413471874887712768)]} 0 1 [junit4:junit4] 2> 14673 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14674 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 14674 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[131]} 0 7 [junit4:junit4] 2> 14678 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[132 (1413471874902392832)]} 0 1 [junit4:junit4] 2> 14684 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14685 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[132 (1413471874905538560)]} 0 5 [junit4:junit4] 2> 14703 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[133 (1413471874912878592)]} 0 16 [junit4:junit4] 2> 14712 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14713 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14713 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[133]} 0 7 [junit4:junit4] 2> 14718 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[134 (1413471874943287296)]} 0 2 [junit4:junit4] 2> 14725 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14726 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[134 (1413471874948530176)]} 0 5 [junit4:junit4] 2> 14729 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[135 (1413471874955870208)]} 0 1 [junit4:junit4] 2> 14738 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14738 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14739 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[135]} 0 7 [junit4:junit4] 2> 14742 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[136 (1413471874969501696)]} 0 1 [junit4:junit4] 2> 14750 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14750 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14751 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[136]} 0 7 [junit4:junit4] 2> 14755 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[137 (1413471874983133184)]} 0 1 [junit4:junit4] 2> 14761 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14762 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[137 (1413471874986278912)]} 0 5 [junit4:junit4] 2> 14765 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[138 (1413471874993618944)]} 0 1 [junit4:junit4] 2> 14774 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14774 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14775 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[138]} 0 7 [junit4:junit4] 2> 14779 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[139 (1413471875008299008)]} 0 1 [junit4:junit4] 2> 14787 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14787 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14788 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[139]} 0 7 [junit4:junit4] 2> 14792 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[140 (1413471875021930496)]} 0 1 [junit4:junit4] 2> 14801 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14801 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14802 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[140]} 0 7 [junit4:junit4] 2> 14805 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[141 (1413471875035561984)]} 0 1 [junit4:junit4] 2> 14813 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14813 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14814 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[141]} 0 7 [junit4:junit4] 2> 14817 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[142 (1413471875048144896)]} 0 1 [junit4:junit4] 2> 14852 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D15=20 [junit4:junit4] 2> 14853 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[142 (1413471875051290624)]} 0 34 [junit4:junit4] 2> 14870 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[143 (1413471875090087936)]} 0 14 [junit4:junit4] 2> 14883 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14884 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D8=20 [junit4:junit4] 2> 14884 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[143]} 0 11 [junit4:junit4] 2> 14889 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[144 (1413471875122593792)]} 0 2 [junit4:junit4] 2> 14899 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14899 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 14900 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[144]} 0 8 [junit4:junit4] 2> 14903 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[145 (1413471875138322432)]} 0 1 [junit4:junit4] 2> 14910 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14910 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[145 (1413471875142516736)]} 0 4 [junit4:junit4] 2> 14914 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[146 (1413471875149856768)]} 0 1 [junit4:junit4] 2> 14950 T1440 C145 P20090 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D16=20 [junit4:junit4] 2> 14950 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[146 (1413471875154051072)]} 0 33 [junit4:junit4] 2> 14954 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[147 (1413471875191799808)]} 0 1 [junit4:junit4] 2> 14963 T1454 C147 P20094 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20086/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14964 T1434 C148 P20086 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20083/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 14964 T1418 C146 P20083 /update {wt=3Djavabin&version= =3D2} {add=3D[147]} 0 7 [junit4:junit4] 2> 14968 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[148 (1413471875206479872)]} 0 1 [junit4:junit4] 2> 14980 T1440 C145 P20090 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:20083/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 14981 T1421 C146 P20083 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D8=20 [junit4:junit4] 2> 14982 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[148]} 0 11 [junit4:junit4] 2> 14985 T1402 C149 P20080 /update {wt=3Djavabin&version= =3D2} {add=3D[149 (1413471875224305664)]} 0 1 [junit4:junit4] 2> 14991 T1454 C147 P20094 REQ /update {distrib.from=3Dht= tp://127.0.0.1:20086/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 14991 T1432 C148 P20086 /update {wt=3Djavabin&version= =3D2} {add=3D[149 (1413471875227451392)]} 0 4 [junit4:junit4] 2> 14992 T1390 oasc.AbstractDistribZkTestBase.waitForReco= veriesToFinish Wait for recoveries to finish - collection: collection1 fail= OnTimeout:true timeout (sec):3600 [junit4:junit4] 2> 14993 T1390 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 14995 T1402 C149 P20080 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15613 T1402 C149 P20080 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/control/data/index,segFN=3D= segments_2,generation=3D2,filenames=3D[_0_MockRandom_0.tib, _0.fnm, _0_Puls= ing40_0.tim, _0_Memory_0.ram, _0_MockVariableIntBlock_0.doc, _0_MockRandom_= 0.prx, _0_nrm.cfs, _0_MockVariableIntBlock_0.skp, _0_Pulsing40_0.prx, _0_Pu= lsing40_0.frq, _0_MockVariableIntBlock_0.pyl, _0_MockRandom_0.frq, _0_MockR= andom_0.sd, _0_MockVariableIntBlock_0.tib, _0_MockVariableIntBlock_0.frq, _= 0_nrm.cfe, _0_Pulsing40_0.tip, _0_MockRandom_0.tii, _0_MockVariableIntBlock= _0.pos, segments_2, _0.fdx, _0.si, _0.fdt, _0_MockVariableIntBlock_0.tii] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-NightlyTests-4.x/solr/build/solr-core/test/J1/org.apache.s= olr.cloud.BasicDistributedZk2Test-1347991809383/control/data/index,segFN=3D= segments_3,generation=3D3,filenames=3D[_5_nrm.cfe, _5_Memory_0.ram, _3_Mock= Random_0.prx, _3_MockVariableIntBlock_0.frq, _0_MockRandom_0.prx, _7.si, _3= .fdt, _4_MockVariableIntBlock_0.tii, _1_MockRandom_0.pos, _0_Pulsing40_0.fr= q, _7_Memory_0.ram, _7.fnm, _5_MockVariableIntBlock_0.tii, _5_MockRandom_0.= pyl, _5_Pulsing40_0.tip, _6_MockVariableIntBlock_0.tii, _5_Pulsing40_0.tim,= _5_MockRandom_0.pos, _1_MockRandom_0.tiv, _6_MockVariableIntBlock_0.tib, _= 0_Pulsing40_0.tim, _1_MockVariableIntBlock_0.tii, _0_Memory_0.ram, _4_MockV= ariableIntBlock_0.doc, _2.fdt, _3_Pulsing40_0.tip, _7.fdx, _1_MockRandom_0.= sd, _7_MockVariableIntBlock_0.frq, _2.fdx, _7.fdt, _3_Pulsing40_0.tim, _1_M= ockVariableIntBlock_0.tib, _7_MockRandom_0.prx, _1.fdx, _0_MockRandom_0.frq= , _7_Pulsing40_0.frq, _4_MockRandom_0.pr [...truncated too long message...] per.close Session: 0x139daa1ad030009 closed [junit4:junit4] 2> 164331 T3234 oejsh.ContextHandler.doStop stopped o.e.j= .s.ServletContextHandler{/solr,null} [junit4:junit4] 2> 164770 T3234 oas.SolrTestCaseJ4.tearDown ###Ending tes= tDistribSearch [junit4:junit4] 2> NOTE: download the large Jenkins line-docs file by run= ning 'ant get-jenkins-line-docs' in the lucene directory. [junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DSyncSlice= Test -Dtests.method=3DtestDistribSearch -Dtests.seed=3D9266E8D41AC43129 -Dt= ests.multiplier=3D3 -Dtests.nightly=3Dtrue -Dtests.slow=3Dtrue -Dtests.line= docsfile=3D/home/hudson/lucene-data/enwiki.random.lines.txt -Dtests.locale= =3Dsv -Dtests.timezone=3DAmerica/Rosario -Dtests.file.encoding=3DISO-8859-1 [junit4:junit4] FAILURE 165s J1 | SyncSliceTest.testDistribSearch <<< [junit4:junit4] > Throwable #1: java.lang.AssertionError: There are stil= l nodes recoverying [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([9266E8D41AC43= 129:138066CC6D9B5115]:0) [junit4:junit4] > =09at org.junit.Assert.fail(Assert.java:93) [junit4:junit4] > =09at org.apache.solr.cloud.AbstractDistribZkTestBase.= waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154) [junit4:junit4] > =09at org.apache.solr.cloud.AbstractFullDistribZkTestB= ase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:560) [junit4:junit4] > =09at org.apache.solr.cloud.AbstractFullDistribZkTestB= ase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1135) [junit4:junit4] > =09at org.apache.solr.cloud.SyncSliceTest.doTest(SyncS= liceTest.java:162) [junit4:junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase.te= stDistribSearch(BaseDistributedSearchTestCase.java:695) [junit4:junit4] > =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Nat= ive Method) [junit4:junit4] > =09at sun.reflect.NativeMethodAccessorImpl.invoke(Nati= veMethodAccessorImpl.java:57) [junit4:junit4] > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(= DelegatingMethodAccessorImpl.java:43) [junit4:junit4] > =09at java.lang.reflect.Method.invoke(Method.java:616) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.invoke(RandomizedRunner.java:1559) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.access$600(RandomizedRunner.java:79) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$6.evaluate(RandomizedRunner.java:737) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$7.evaluate(RandomizedRunner.java:773) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$8.evaluate(RandomizedRunner.java:787) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleSetupTeardownChai= ned$1.evaluate(TestRuleSetupTeardownChained.java:50) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleFieldCacheSanity$= 1.evaluate(TestRuleFieldCacheSanity.java:51) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:45) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleThreadAndTestName= $1.evaluate(TestRuleThreadAndTestName.java:48) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol.forkTimeoutingTask(ThreadLeakControl.java:782) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$3.evaluate(ThreadLeakControl.java:442) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.runSingleTest(RandomizedRunner.java:746) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$3.evaluate(RandomizedRunner.java:648) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$4.evaluate(RandomizedRunner.java:682) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$5.evaluate(RandomizedRunner.java:693) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:45) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleStoreClassName$1.= evaluate(TestRuleStoreClassName.java:42) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleAssertionsRequire= d$1.evaluate(TestRuleAssertionsRequired.java:43) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$= 1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at java.lang.Thread.run(Thread.java:679) [junit4:junit4] 2> 164826 T3234 oas.SolrTestCaseJ4.deleteCore ###deleteCo= re [junit4:junit4] 2> NOTE: test params are: codec=3DLucene40: {range_facet_= l=3DPostingsFormat(name=3DMemory doPackFST=3D true), text=3DPostingsFormat(= name=3DMockRandom), _version_=3DPostingsFormat(name=3DNestedPulsing), rnd_b= =3DMockFixedIntBlock(blockSize=3D1162), intDefault=3DPostingsFormat(name=3D= NestedPulsing), id=3DMockFixedIntBlock(blockSize=3D1162), timestamp=3DPosti= ngsFormat(name=3DMemory doPackFST=3D true), a_t=3DPostingsFormat(name=3DMem= ory doPackFST=3D true), range_facet_sl=3DMockFixedIntBlock(blockSize=3D1162= ), range_facet_si=3DPostingsFormat(name=3DMemory doPackFST=3D true), other_= tl1=3DPostingsFormat(name=3DNestedPulsing), multiDefault=3DPostingsFormat(n= ame=3DMemory doPackFST=3D true), a_si=3DPostingsFormat(name=3DNestedPulsing= )}, sim=3DDefaultSimilarity, locale=3Dsv, timezone=3DAmerica/Rosario [junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. = 1.6.0_32 (64-bit)/cpus=3D16,threads=3D1,free=3D164701584,total=3D328073216 [junit4:junit4] 2> NOTE: All tests run in this JVM: [HighlighterTest, Tes= tRecovery, TestReversedWildcardFilterFactory, TestCodecSupport, TestValueSo= urceCache, TestUpdate, DOMUtilTest, BadIndexSchemaTest, TestSurroundQueryPa= rser, BasicFunctionalityTest, OpenExchangeRatesOrgProviderTest, StatelessSc= riptUpdateProcessorFactoryTest, DocumentBuilderTest, PolyFieldTest, StatsCo= mponentTest, PrimitiveFieldTypeTest, OverseerTest, UUIDFieldTest, RAMDirect= oryFactoryTest, ClusterStateUpdateTest, TestWriterPerf, ZkSolrClientTest, D= irectUpdateHandlerTest, DistributedQueryElevationComponentTest, TestRealTim= eGet, TestReload, TestFoldingMultitermQuery, TestPropInject, TestSolrDeleti= onPolicy2, TestMergePolicyConfig, URLClassifyProcessorTest, FullSolrCloudDi= stribCmdsTest, TestPluginEnable, TestQueryUtils, QueryParsingTest, TestCSVL= oader, PeerSyncTest, LeaderElectionTest, LukeRequestHandlerTest, TestRemote= Streaming, StandardRequestHandlerTest, SolrIndexConfigTest, BasicDistribute= dZk2Test, TestDistributedSearch, XsltUpdateRequestHandlerTest, DocumentAnal= ysisRequestHandlerTest, SpellingQueryConverterTest, TestTrie, TestReplicati= onHandler, TestUtils, TestSolrDeletionPolicy1, TestDFRSimilarityFactory, Te= stPhraseSuggestions, IndexReaderFactoryTest, BasicDistributedZkTest, Numeri= cFieldsTest, MultiTermTest, TestJmxMonitoredMap, TestBM25SimilarityFactory,= TimeZoneUtilsTest, FileUtilsTest, TestSolrXMLSerializer, TestLRUCache, Sys= temInfoHandlerTest, ZkNodePropsTest, AnalysisAfterCoreReloadTest, SyncSlice= Test] [junit4:junit4] Completed on J1 in 164.86s, 1 test, 1 failure <<< FAILURES! [...truncated 16 lines...] BUILD FAILED /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/build.= xml:32: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/solr/b= uild.xml:146: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene= /module-build.xml:63: The following error occurred while executing this lin= e: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene= /common-build.xml:1136: The following error occurred while executing this l= ine: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-4.x/lucene= /common-build.xml:810: There were test failures: 230 suites, 951 tests, 2 f= ailures, 7 ignored (2 assumptions) Total time: 74 minutes 34 seconds Build step 'Invoke Ant' marked build as failure Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_237_1747274640.1347992966360 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_237_1747274640.1347992966360--