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 C3E08D493 for ; Sun, 28 Oct 2012 07:20:31 +0000 (UTC) Received: (qmail 22516 invoked by uid 500); 28 Oct 2012 07:20:29 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 22288 invoked by uid 500); 28 Oct 2012 07:20:29 -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 22268 invoked by uid 99); 28 Oct 2012 07:20:28 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 28 Oct 2012 07:20:28 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=NORMAL_HTTP_TO_IP,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: local policy) Received: from [188.138.97.18] (HELO mail.sd-datasolutions.de) (188.138.97.18) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 28 Oct 2012 07:20:07 +0000 Received: from serv1 (localhost.localdomain [127.0.0.1]) by mail.sd-datasolutions.de (Postfix) with ESMTP id 7E11614AA03D for ; Sun, 28 Oct 2012 07:19:46 +0000 (UTC) Date: Sun, 28 Oct 2012 07:19:30 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <2044803215.126.1351408786506.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_07) - Build # 1337 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_125_1135944167.1351408770303" X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_125_1135944167.1351408770303 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows/133= 7/ Java: 32bit/jdk1.7.0_07 -client -XX:+UseConcMarkSweepGC 4 tests failed. REGRESSION: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearc= h Error Message: There are still nodes recoverying Stack Trace: java.lang.AssertionError: There are still nodes recoverying =09at __randomizedtesting.SeedInfo.seed([763A6F09518A4C24:F7DCE11126D52C18]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:155) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:116) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:111) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveries= ToFinish(AbstractFullDistribZkTestBase.java:553) =09at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributed= Zk2Test.java:74) =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:601) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java: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:722) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZk= 2Test Error Message: ERROR: SolrIndexSearcher opens=3D11 closes=3D10 Stack Trace: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=3D11 closes=3D10 =09at __randomizedtesting.SeedInfo.seed([763A6F09518A4C24]:0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.ja= va:243) =09at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:97) =09at sun.reflect.GeneratedMethodAccessor47.invoke(Unknown Source) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:601) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:700) =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:722) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZk= 2Test Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.BasicDistributedZ= k2Test: 1) Thread[id=3D1753, name=3DsearcherExecutor-1327-thread-1, sta= te=3DWAITING, group=3DTGRP-BasicDistributedZk2Test] at sun.misc.Uns= afe.park(Native Method) at java.util.concurrent.locks.LockSupport.p= ark(LockSupport.java:186) at java.util.concurrent.locks.AbstractQue= uedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)= at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQue= ue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(Thr= eadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecut= or.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.= ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.= lang.Thread.run(Thread.java:722) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SU= ITE scope at org.apache.solr.cloud.BasicDistributedZk2Test:=20 1) Thread[id=3D1753, name=3DsearcherExecutor-1327-thread-1, state=3DWAIT= ING, group=3DTGRP-BasicDistributedZk2Test] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186= ) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionO= bject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueu= e.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:603) at java.lang.Thread.run(Thread.java:722) =09at __randomizedtesting.SeedInfo.seed([763A6F09518A4C24]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZk= 2Test Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id= =3D1753, name=3DsearcherExecutor-1327-thread-1, state=3DWAITING, group=3DTG= RP-BasicDistributedZk2Test] at sun.misc.Unsafe.park(Native Method) = at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186= ) at java.util.concurrent.locks.AbstractQueuedSynchronizer$Conditio= nObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.co= ncurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at = java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:104= 3) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolE= xecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worke= r.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.j= ava:722) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie = threads that couldn't be terminated: 1) Thread[id=3D1753, name=3DsearcherExecutor-1327-thread-1, state=3DWAIT= ING, group=3DTGRP-BasicDistributedZk2Test] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186= ) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionO= bject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueu= e.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:603) at java.lang.Thread.run(Thread.java:722) =09at __randomizedtesting.SeedInfo.seed([763A6F09518A4C24]:0) Build Log: [...truncated 8742 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test [junit4:junit4] 2> 3 T1704 oas.SolrTestCaseJ4.setUp ###Starting testDistr= ibSearch [junit4:junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Luce= ne-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicDistribu= tedZk2Test-1351407702198 [junit4:junit4] 2> 6 T1704 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 10 T1705 oazs.ZooKeeperServer.setTickTime tickTime set= to 1000 [junit4:junit4] 2> 11 T1705 oazs.NIOServerCnxn$Factory. binding to = port 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 12 T1705 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 109 T1704 oasc.ZkTestServer.run start zk server on por= t:54823 [junit4:junit4] 2> 110 T1704 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:54823 sessionTimeout=3D10000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@5ec629 [junit4:junit4] 2> 111 T1704 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 112 T1710 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:54823 [junit4:junit4] 2> 114 T1710 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to 127.0.0.1/127.0.0.1:54823, initiating sessio= n [junit4:junit4] 2> 114 T1706 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /127.0.0.1:54826 [junit4:junit4] 2> 115 T1706 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /127.0.0.1:54826 [junit4:junit4] 2> 116 T1708 oazsp.FileTxnLog.append Creating new log fil= e: log.1 [junit4:junit4] 2> 129 T1710 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server 127.0.0.1/127.0.0.1:54823, session= id =3D 0x13aa62d10bf0000, negotiated timeout =3D 10000 [junit4:junit4] 2> 129 T1711 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@5ec629 name:ZooKeeperConnection = Watcher:127.0.0.1:54823 got event WatchedEvent state:SyncConnected type:Non= e path:null path:null type:None [junit4:junit4] 2> 129 T1708 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x13aa62d10bf0000 with negotiated timeout 10000 for client /12= 7.0.0.1:54826 [junit4:junit4] 2> 129 T1704 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 129 T1704 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 148 T1709 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x13aa62d10bf0000 [junit4:junit4] 2> 167 T1704 oaz.ZooKeeper.close Session: 0x13aa62d10bf00= 00 closed [junit4:junit4] 2> 168 T1711 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 169 T1706 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:54826 which had sessionid 0x13aa62d10bf0000 [junit4:junit4] 2> 169 T1704 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:54823/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1116ce4 [junit4:junit4] 2> 171 T1704 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 171 T1712 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:54823 [junit4:junit4] 2> 172 T1712 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to 127.0.0.1/127.0.0.1:54823, initiating sessio= n [junit4:junit4] 2> 172 T1706 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /127.0.0.1:54829 [junit4:junit4] 2> 172 T1706 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /127.0.0.1:54829 [junit4:junit4] 2> 197 T1708 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x13aa62d10bf0001 with negotiated timeout 10000 for client /12= 7.0.0.1:54829 [junit4:junit4] 2> 198 T1712 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server 127.0.0.1/127.0.0.1:54823, session= id =3D 0x13aa62d10bf0001, negotiated timeout =3D 10000 [junit4:junit4] 2> 200 T1713 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1116ce4 name:ZooKeeperConnection= Watcher:127.0.0.1:54823/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 201 T1704 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 201 T1704 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4:junit4] 2> 240 T1704 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4:junit4] 2> 249 T1704 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4:junit4] 2> 270 T1704 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4:junit4] 2> 301 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.= xml [junit4:junit4] 2> 312 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 360 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4:junit4] 2> 361 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 391 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.= xml [junit4:junit4] 2> 393 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 410 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.tx= t [junit4:junit4] 2> 411 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 429 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.tx= t [junit4:junit4] 2> 430 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 439 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4:junit4] 2> 440 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 459 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/o= pen-exchange-rates.json [junit4:junit4] 2> 460 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 486 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf= 1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 487 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 496 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synon= yms.txt [junit4:junit4] 2> 497 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 509 T1704 oasc.AbstractZkTestCase.putConfig put C:\Use= rs\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\te= st-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4:junit4] 2> 511 T1704 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 515 T1709 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x13aa62d10bf0001 [junit4:junit4] 2> 519 T1706 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:54829 which had sessionid 0x13aa62d10bf0001 [junit4:junit4] 2> 519 T1713 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 519 T1704 oaz.ZooKeeper.close Session: 0x13aa62d10bf00= 01 closed [junit4:junit4] 2> 883 T1704 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 889 T1704 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@0.0.0.0:54830 [junit4:junit4] 2> 890 T1704 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 890 T1704 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Sol= r-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicD= istributedZk2Test-controljetty-1351407702714 [junit4:junit4] 2> 891 T1704 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basic= DistributedZk2Test-controljetty-1351407702714\' [junit4:junit4] 2> 931 T1704 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 932 T1704 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 932 T1704 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Sol= r-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicD= istributedZk2Test-controljetty-1351407702714 [junit4:junit4] 2> 932 T1704 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windo= ws\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2= Test-controljetty-1351407702714\solr.xml [junit4:junit4] 2> 933 T1704 oasc.CoreContainer. New CoreContainer = 30067815 [junit4:junit4] 2> 933 T1704 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windo= ws\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2= Test-controljetty-1351407702714\' [junit4:junit4] 2> 934 T1704 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk= -Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistribu= tedZk2Test-controljetty-1351407702714\' [junit4:junit4] 2> 964 T1704 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 984 T1704 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:54823/solr [junit4:junit4] 2> 985 T1704 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:54823/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1223695 [junit4:junit4] 2> 986 T1723 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:54823 [junit4:junit4] 2> 986 T1704 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 988 T1723 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to 127.0.0.1/127.0.0.1:54823, initiating sessio= n [junit4:junit4] 2> 988 T1706 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /127.0.0.1:54835 [junit4:junit4] 2> 989 T1706 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /127.0.0.1:54835 [junit4:junit4] 2> 1008 T1708 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x13aa62d10bf0002 with negotiated timeout 5000 for client /12= 7.0.0.1:54835 [junit4:junit4] 2> 1008 T1723 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:54823, sessio= nid =3D 0x13aa62d10bf0002, negotiated timeout =3D 5000 [junit4:junit4] 2> 1010 T1724 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@1223695 name:ZooKeeperConnectio= n Watcher:127.0.0.1:54823/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 1011 T1704 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 1027 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 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> 1061 T1704 oascc.SolrZkClient.makePath makePath: /live= _nodes [junit4:junit4] 2> 1083 T1704 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:54830_solr [junit4:junit4] 2> 1085 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:del= ete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:54830_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:54830_solr [junit4:junit4] 2> 1115 T1704 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:54830_solr [junit4:junit4] 2> 1138 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 1140 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 1140 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 1142 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1143 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 1143 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1145 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1145 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 1146 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 1146 T1704 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 1167 T1704 oascc.SolrZkClient.makePath makePath: /over= seer_elect/election [junit4:junit4] 2> 1266 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:del= ete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/ov= erseer_elect/leader [junit4:junit4] 2> 1297 T1704 oascc.SolrZkClient.makePath makePath: /over= seer_elect/leader [junit4:junit4] 2> 1321 T1704 oasc.Overseer.start Overseer (id=3D88565855= 171837954-127.0.0.1:54830_solr-n_0000000000) starting [junit4:junit4] 2> 1322 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:cre= ate cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1343 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:cre= ate cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1375 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:cre= ate cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1408 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:cre= ate cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1432 T1726 oasc.OverseerCollectionProcessor.run Proces= s current queue of collection creations [junit4:junit4] 2> 1433 T1704 oascc.SolrZkClient.makePath makePath: /clus= terstate.json [junit4:junit4] 2> 1485 T1704 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1493 T1725 oasc.Overseer$ClusterStateUpdater.run Start= ing to work on the main queue [junit4:junit4] 2> 1504 T1704 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basic= DistributedZk2Test-controljetty-1351407702714\collection1 [junit4:junit4] 2> 1505 T1704 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:control_collection [junit4:junit4] 2> 1506 T1704 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 1506 T1704 oasc.ZkController.readConfigName Load colle= ction config from:/collections/control_collection [junit4:junit4] 2> 1511 T1704 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistrib= utedZk2Test-controljetty-1351407702714\collection1\' [junit4:junit4] 2> 1514 T1704 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/sol= r/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-con= troljetty-1351407702714/collection1/lib/classes/' to classloader [junit4:junit4] 2> 1514 T1704 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/sol= r/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-con= troljetty-1351407702714/collection1/lib/README' to classloader [junit4:junit4] 2> 1554 T1704 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 1620 T1704 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 1622 T1704 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 1626 T1704 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 2366 T1704 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 2381 T1704 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 2396 T1704 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 2419 T1704 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 2423 T1704 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 2429 T1704 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2431 T1704 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2433 T1704 oasc.SolrCore. [collection1] Opening = new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\s= olr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test= -controljetty-1351407702714\collection1\, dataDir=3D.\org.apache.solr.cloud= .BasicDistributedZk2Test-1351407702197/control/data\ [junit4:junit4] 2> 2433 T1704 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1128cc [junit4:junit4] 2> 2434 T1704 oasc.SolrCore.initDirectoryFactory solr.NRT= CachingDirectoryFactory [junit4:junit4] 2> 2435 T1704 oasc.CachingDirectoryFactory.injectLockFact= ory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucen= e.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk= -Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribute= dZk2Test-1351407702197\control\data lockFactory=3Dorg.apache.lucene.store.N= ativeFSLockFactory@ad0057; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0) assuming= 'simple' [junit4:junit4] 2> 2435 T1704 oasc.CachingDirectoryFactory.get return new= directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\so= lr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-= 1351407702197\control\data forceNew:false [junit4:junit4] 2> 2436 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\control\data [junit4:junit4] 2> 2436 T1704 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZk= 2Test-1351407702197/control/data\index/ [junit4:junit4] 2> 2437 T1704 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\control\data\index' doesn't exist. Creating new index... [junit4:junit4] 2> 2438 T1704 oasc.CachingDirectoryFactory.get return new= directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\so= lr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-= 1351407702197\control\data\index forceNew:false [junit4:junit4] 2> 2450 T1704 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\control\data\index lockFactory=3Dorg.apache.lucene.= store.NativeFSLockFactory@b64242; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),s= egFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 2451 T1704 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 2453 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\control\data\index [junit4:junit4] 2> 2454 T1704 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 2455 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 2457 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 2457 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 2458 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 2458 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 2459 T1704 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 2459 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 2460 T1704 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 2460 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 2461 T1704 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 2462 T1704 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 2462 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 2463 T1704 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 2463 T1704 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 2464 T1704 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 2464 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 2465 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2465 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2466 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 2466 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 2467 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 2467 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2468 T1704 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2468 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 2469 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 2481 T1704 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 2488 T1704 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 2490 T1704 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> 2493 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\control\data [junit4:junit4] 2> 2495 T1704 oass.SolrIndexSearcher. Opening Searc= her@17a4f0c main [junit4:junit4] 2> 2497 T1704 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 2497 T1704 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 2498 T1704 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 2560 T1704 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 2832 T1727 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@17a4f0c main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 2835 T1704 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 2836 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:cre= ate cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer/queue Error:KeeperErrorCode =3D NoNode for /solr/overseer/= queue [junit4:junit4] 2> 2890 T1704 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 2892 T1704 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:54830/solr shardId:control_shard [junit4:junit4] 2> 2893 T1704 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leader_elect/control_shard/election [junit4:junit4] 2> 2926 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:del= ete cxid:0x65 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> 2928 T1704 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 2929 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:cre= ate cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3154 T1725 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3155 T1725 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3Dnull message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":null, [junit4:junit4] 2> =09 "shard":"control_shard", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"control_collection", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54830_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54830/solr"} [junit4:junit4] 2> 3156 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:cre= ate cxid:0x6d 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> 3318 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (1) [junit4:junit4] 2> 3483 T1704 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 3483 T1704 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 3485 T1704 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:54830/solr/collection1/ [junit4:junit4] 2> 3485 T1704 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 3487 T1704 oasc.SyncStrategy.syncToMe http://127.0.0.1= :54830/solr/collection1/ has no replicas [junit4:junit4] 2> 3487 T1704 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:54830/solr/collection1/ [junit4:junit4] 2> 3488 T1704 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leaders/control_shard [junit4:junit4] 2> 3495 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0002 type:cre= ate cxid:0x8c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3915 T1725 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3929 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (1) [junit4:junit4] 2> 3951 T1704 oasc.ZkController.register We are http://12= 7.0.0.1:54830/solr/collection1/ and leader is http://127.0.0.1:54830/solr/c= ollection1/ [junit4:junit4] 2> 3952 T1704 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:54830/solr [junit4:junit4] 2> 3952 T1704 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 3953 T1704 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 3958 T1704 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3959 T1704 oass.SolrDispatchFilter.init user.dir=3DC:\= Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core= \test\J0 [junit4:junit4] 2> 3959 T1704 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 3960 T1704 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 3967 T1704 oascsi.HttpClientUtil.createClient Creating= new http client, config: [junit4:junit4] 2> 3973 T1704 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:54823/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@10d67e9 [junit4:junit4] 2> 3975 T1728 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:54823 [junit4:junit4] 2> 3975 T1704 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 3978 T1728 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:54823, initiating sessi= on [junit4:junit4] 2> 3978 T1706 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:54838 [junit4:junit4] 2> 3979 T1706 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:54838 [junit4:junit4] 2> 3981 T1708 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x13aa62d10bf0003 with negotiated timeout 10000 for client /1= 27.0.0.1:54838 [junit4:junit4] 2> 3981 T1728 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:54823, sessio= nid =3D 0x13aa62d10bf0003, negotiated timeout =3D 10000 [junit4:junit4] 2> 3982 T1729 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@10d67e9 name:ZooKeeperConnectio= n Watcher:127.0.0.1:54823/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 3982 T1704 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 3984 T1704 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 3986 T1704 oasc.ChaosMonkey.monkeyLog monkey: init - e= xpire sessions:true cause connection loss:true [junit4:junit4] 2> 4346 T1704 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 4350 T1704 oejs.AbstractConnector.doStart Started Sele= ctChannelConnector@0.0.0.0:54839 [junit4:junit4] 2> 4351 T1704 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4351 T1704 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basic= DistributedZk2Test-jetty1-1351407706180 [junit4:junit4] 2> 4352 T1704 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basi= cDistributedZk2Test-jetty1-1351407706180\' [junit4:junit4] 2> 4383 T1704 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 4383 T1704 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4383 T1704 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basic= DistributedZk2Test-jetty1-1351407706180 [junit4:junit4] 2> 4384 T1704 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Wind= ows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk= 2Test-jetty1-1351407706180\solr.xml [junit4:junit4] 2> 4384 T1704 oasc.CoreContainer. New CoreContainer= 17888890 [junit4:junit4] 2> 4384 T1704 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Wind= ows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk= 2Test-jetty1-1351407706180\' [junit4:junit4] 2> 4384 T1704 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistrib= utedZk2Test-jetty1-1351407706180\' [junit4:junit4] 2> 4405 T1704 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 4427 T1704 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:54823/solr [junit4:junit4] 2> 4428 T1704 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:54823/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@79099e [junit4:junit4] 2> 4429 T1739 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:54823 [junit4:junit4] 2> 4429 T1704 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 4432 T1739 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:54823, initiating sessi= on [junit4:junit4] 2> 4432 T1706 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:54844 [junit4:junit4] 2> 4432 T1706 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:54844 [junit4:junit4] 2> 4449 T1708 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x13aa62d10bf0004 with negotiated timeout 5000 for client /12= 7.0.0.1:54844 [junit4:junit4] 2> 4450 T1739 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:54823, sessio= nid =3D 0x13aa62d10bf0004, negotiated timeout =3D 5000 [junit4:junit4] 2> 4450 T1740 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@79099e name:ZooKeeperConnection= Watcher:127.0.0.1:54823/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 4451 T1704 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 4452 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0004 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> 4477 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0004 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> 4489 T1704 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:54839_solr [junit4:junit4] 2> 4490 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0004 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:54839_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:54839_solr [junit4:junit4] 2> 4509 T1704 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:54839_solr [junit4:junit4] 2> 4510 T1725 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4511 T1725 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3Dnull message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":null, [junit4:junit4] 2> =09 "shard":"control_shard", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"control_collection", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54830_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54830/solr"} [junit4:junit4] 2> 4543 T1729 oascc.ZkStateReader$3.process Updating live= nodes... (2) [junit4:junit4] 2> 4544 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 4544 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 4545 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 4545 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 4546 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 4546 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 4546 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 4547 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 4547 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 4548 T1704 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 4619 T1729 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 4620 T1704 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 4653 T1724 oascc.ZkStateReader$3.process Updating live= nodes... (2) [junit4:junit4] 2> 4655 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 4658 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 4663 T1704 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basic= DistributedZk2Test-jetty1-1351407706180\collection1 [junit4:junit4] 2> 4663 T1704 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 4664 T1704 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 4664 T1704 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 4666 T1704 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistrib= utedZk2Test-jetty1-1351407706180\collection1\' [junit4:junit4] 2> 4668 T1704 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/sol= r/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jet= ty1-1351407706180/collection1/lib/classes/' to classloader [junit4:junit4] 2> 4669 T1704 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/sol= r/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jet= ty1-1351407706180/collection1/lib/README' to classloader [junit4:junit4] 2> 4714 T1704 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 4800 T1704 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 4804 T1704 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 4811 T1704 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 5686 T1704 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 5700 T1704 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 5704 T1704 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 5722 T1704 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5727 T1704 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5733 T1704 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5735 T1704 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5737 T1704 oasc.SolrCore. [collection1] Opening = new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\s= olr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test= -jetty1-1351407706180\collection1\, dataDir=3D.\org.apache.solr.cloud.Basic= DistributedZk2Test-1351407702197/jetty1\ [junit4:junit4] 2> 5737 T1704 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1128cc [junit4:junit4] 2> 5737 T1704 oasc.SolrCore.initDirectoryFactory solr.NRT= CachingDirectoryFactory [junit4:junit4] 2> 5739 T1704 oasc.CachingDirectoryFactory.injectLockFact= ory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucen= e.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk= -Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribute= dZk2Test-1351407702197\jetty1 lockFactory=3Dorg.apache.lucene.store.NativeF= SLockFactory@5611c; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0) assuming 'simpl= e' [junit4:junit4] 2> 5741 T1704 oasc.CachingDirectoryFactory.get return new= directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\so= lr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-= 1351407702197\jetty1 forceNew:false [junit4:junit4] 2> 5741 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\jetty1 [junit4:junit4] 2> 5741 T1704 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZk= 2Test-1351407702197/jetty1\index/ [junit4:junit4] 2> 5742 T1704 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty1\index' doesn't exist. Creating new index... [junit4:junit4] 2> 5744 T1704 oasc.CachingDirectoryFactory.get return new= directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\so= lr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-= 1351407702197\jetty1\index forceNew:false [junit4:junit4] 2> 5782 T1704 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty1\index lockFactory=3Dorg.apache.lucene.store.= NativeFSLockFactory@1dcd021; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 5782 T1704 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 5785 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\jetty1\index [junit4:junit4] 2> 5785 T1704 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 5788 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 5788 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 5789 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 5789 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 5790 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 5790 T1704 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 5791 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 5791 T1704 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 5792 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 5793 T1704 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 5793 T1704 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 5794 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 5794 T1704 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 5795 T1704 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 5795 T1704 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 5796 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 5796 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5797 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5797 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5798 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5799 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 5799 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5800 T1704 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5800 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 5802 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 5814 T1704 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 5820 T1704 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 5822 T1704 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> 5825 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\jetty1 [junit4:junit4] 2> 5828 T1704 oass.SolrIndexSearcher. Opening Searc= her@1912b83 main [junit4:junit4] 2> 5832 T1704 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 5833 T1704 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 5834 T1704 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 5880 T1704 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 6106 T1741 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1912b83 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 6111 T1704 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 6309 T1725 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6310 T1725 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54839_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54839/solr"} [junit4:junit4] 2> 6310 T1725 oasc.Overseer$ClusterStateUpdater.createCol= lection Create collection collection1 with numShards 2 [junit4:junit4] 2> 6320 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 6320 T1729 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 6320 T1740 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 6674 T1704 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 6674 T1704 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:54839/solr shardId:shard1 [junit4:junit4] 2> 6676 T1704 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 6693 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0004 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> 6695 T1704 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 6696 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0004 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> 6702 T1704 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 6702 T1704 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 6703 T1704 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:54839/solr/collection1/ [junit4:junit4] 2> 6703 T1704 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 6705 T1704 oasc.SyncStrategy.syncToMe http://127.0.0.1= :54839/solr/collection1/ has no replicas [junit4:junit4] 2> 6705 T1704 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:54839/solr/collection1/ [junit4:junit4] 2> 6706 T1704 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard1 [junit4:junit4] 2> 6716 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0004 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> 6880 T1725 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7037 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 7037 T1740 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 7037 T1729 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 7057 T1704 oasc.ZkController.register We are http://12= 7.0.0.1:54839/solr/collection1/ and leader is http://127.0.0.1:54839/solr/c= ollection1/ [junit4:junit4] 2> 7057 T1704 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:54839/solr [junit4:junit4] 2> 7058 T1704 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 7058 T1704 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 7103 T1704 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7138 T1704 oass.SolrDispatchFilter.init user.dir=3DC:\= Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core= \test\J0 [junit4:junit4] 2> 7139 T1704 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 7140 T1704 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 7468 T1704 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 7473 T1704 oejs.AbstractConnector.doStart Started Sele= ctChannelConnector@0.0.0.0:54845 [junit4:junit4] 2> 7474 T1704 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 7474 T1704 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basic= DistributedZk2Test-jetty2-1351407709343 [junit4:junit4] 2> 7475 T1704 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basi= cDistributedZk2Test-jetty2-1351407709343\' [junit4:junit4] 2> 7515 T1704 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 7515 T1704 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 7516 T1704 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basic= DistributedZk2Test-jetty2-1351407709343 [junit4:junit4] 2> 7516 T1704 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Wind= ows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk= 2Test-jetty2-1351407709343\solr.xml [junit4:junit4] 2> 7516 T1704 oasc.CoreContainer. New CoreContainer= 6795478 [junit4:junit4] 2> 7517 T1704 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Wind= ows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk= 2Test-jetty2-1351407709343\' [junit4:junit4] 2> 7517 T1704 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistrib= utedZk2Test-jetty2-1351407709343\' [junit4:junit4] 2> 7548 T1704 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 7568 T1704 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:54823/solr [junit4:junit4] 2> 7569 T1704 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:54823/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@6baf12 [junit4:junit4] 2> 7570 T1751 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:54823 [junit4:junit4] 2> 7571 T1704 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 7573 T1751 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:54823, initiating sessi= on [junit4:junit4] 2> 7573 T1706 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:54850 [junit4:junit4] 2> 7573 T1706 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:54850 [junit4:junit4] 2> 7591 T1708 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x13aa62d10bf0005 with negotiated timeout 5000 for client /12= 7.0.0.1:54850 [junit4:junit4] 2> 7591 T1751 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:54823, sessio= nid =3D 0x13aa62d10bf0005, negotiated timeout =3D 5000 [junit4:junit4] 2> 7591 T1752 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@6baf12 name:ZooKeeperConnection= Watcher:127.0.0.1:54823/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 7592 T1704 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 7593 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0005 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> 7614 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0005 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> 7647 T1704 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:54845_solr [junit4:junit4] 2> 7648 T1709 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa62d10bf0005 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:54845_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:54845_solr [junit4:junit4] 2> 7667 T1704 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:54845_solr [junit4:junit4] 2> 7701 T1740 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 7702 T1729 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 7702 T1729 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 7702 T1724 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 7703 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 7704 T1740 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 7704 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 7705 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 7705 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 7705 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 7706 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 7706 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 7707 T1725 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7707 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 7707 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 7708 T1725 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54839_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54839/solr"} [junit4:junit4] 2> 7708 T1704 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 7709 T1704 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 7799 T1704 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 7809 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 7809 T1729 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 7809 T1752 oaz.ClientCnxn$EventThread.processEvent SEV= ERE Error while calling watcher java.lang.NullPointerException [junit4:junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader$2.pro= cess(ZkStateReader.java:189) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.proc= essEvent(ClientCnxn.java:526) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(= ClientCnxn.java:502) [junit4:junit4] 2>=20 [junit4:junit4] 2> 7810 T1740 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 7817 T1704 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basic= DistributedZk2Test-jetty2-1351407709343\collection1 [junit4:junit4] 2> 7818 T1704 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 7820 T1704 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 7821 T1704 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 7823 T1704 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistrib= utedZk2Test-jetty2-1351407709343\collection1\' [junit4:junit4] 2> 7825 T1704 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/sol= r/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jet= ty2-1351407709343/collection1/lib/classes/' to classloader [junit4:junit4] 2> 7828 T1704 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/sol= r/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jet= ty2-1351407709343/collection1/lib/README' to classloader [junit4:junit4] 2> 7912 T1704 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 8000 T1704 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 8002 T1704 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 8008 T1704 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 8848 T1704 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 8859 T1704 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 8862 T1704 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 8878 T1704 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 8882 T1704 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 8887 T1704 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 8889 T1704 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 8890 T1704 oasc.SolrCore. [collection1] Opening = new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\s= olr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test= -jetty2-1351407709343\collection1\, dataDir=3D.\org.apache.solr.cloud.Basic= DistributedZk2Test-1351407702197/jetty2\ [junit4:junit4] 2> 8891 T1704 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1128cc [junit4:junit4] 2> 8891 T1704 oasc.SolrCore.initDirectoryFactory solr.NRT= CachingDirectoryFactory [junit4:junit4] 2> 8892 T1704 oasc.CachingDirectoryFactory.injectLockFact= ory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucen= e.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk= -Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribute= dZk2Test-1351407702197\jetty2 lockFactory=3Dorg.apache.lucene.store.NativeF= SLockFactory@ccb62b; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0) assuming 'simp= le' [junit4:junit4] 2> 8892 T1704 oasc.CachingDirectoryFactory.get return new= directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\so= lr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-= 1351407702197\jetty2 forceNew:false [junit4:junit4] 2> 8893 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\jetty2 [junit4:junit4] 2> 8893 T1704 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZk= 2Test-1351407702197/jetty2\index/ [junit4:junit4] 2> 8894 T1704 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty2\index' doesn't exist. Creating new index... [junit4:junit4] 2> 8894 T1704 oasc.CachingDirectoryFactory.get return new= directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\so= lr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-= 1351407702197\jetty2\index forceNew:false [junit4:junit4] 2> 8940 T1704 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty2\index lockFactory=3Dorg.apache.lucene.store.= NativeFSLockFactory@17d13cc; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 8940 T1704 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 8944 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\jetty2\index [junit4:junit4] 2> 8944 T1704 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 8946 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 8946 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 8947 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 8947 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 8948 T1704 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 8948 T1704 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 8949 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 8949 T1704 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 8950 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 8950 T1704 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 8951 T1704 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 8952 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 8952 T1704 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 8953 T1704 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 8953 T1704 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 8954 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 8954 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8955 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8956 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 8956 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 8958 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 8959 T1704 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8960 T1704 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8961 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 8961 T1704 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 8973 T1704 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 8979 T1704 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 8981 T1704 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> 8984 T1704 oasc.CachingDirectoryFactory.close Releasin= g directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\= build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-135= 1407702197\jetty2 [junit4:junit4] 2> 8987 T1704 oass.SolrIndexSearcher. Opening Searc= her@c7ddd8 main [junit4:junit4] 2> 8989 T1704 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 8990 T1704 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 8990 T1704 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 9043 T1704 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 9207 T1753 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@c7ddd8 main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 9211 T1704 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 9479 T1725 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9480 T1725 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54845_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54845/solr"} [junit4:junit4] 2> 9489 T1724 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 9489 T1729 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 9489 T1740 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 70225 T1704 oasc.SolrException.log SEVERE null:org.apa= che.solr.common.SolrException: Could not get shard_id for core: collection1 [junit4:junit4] 2> =09=09at org.apache.solr.cloud.ZkController.doGetShard= IdProcess(ZkController.java:998) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.ZkController.preRegiste= r(ZkController.java:1056) [junit4:junit4] 2> =09=09at org.apache.solr.core.CoreContainer.register(C= oreContainer.java:657) [junit4:junit4] 2> =09=09at org.apache.solr.core.CoreContainer.load(CoreC= ontainer.java:530) [junit4:junit4] 2> =09=09at org.apache.solr.core.CoreContainer.load(CoreC= ontainer.java:351) [junit4:junit4] 2> =09=09at org.apache.solr.core.CoreContainer$Initialize= r.initialize(CoreContainer.java:303) [junit4:junit4] 2> =09=09at org.apache.solr.servlet.SolrDispatchFilter.in= it(SolrDispatchFilter.java:107) [junit4:junit4] 2> =09=09at org.eclipse.jetty.servlet.FilterHolder.doStar= t(FilterHolder.java:114) [junit4:junit4] 2> =09=09at org.eclipse.jetty.util.component.AbstractLife= Cycle.start(AbstractLifeCycle.java:59) [junit4:junit4] 2> =09=09at org.eclipse.jetty.servlet.ServletHandler.init= ialize(ServletHandler.java:754) [junit4:junit4] 2> =09=09at org.eclipse.jetty.servlet.ServletHandler.upda= teMappings(ServletHandler.java:1227) [junit4:junit4] 2> =09=09at org.eclipse.jetty.servlet.ServletHandler.setF= ilterMappings(ServletHandler.java:1263) [junit4:junit4] 2> =09=09at org.eclipse.jetty.servlet.ServletHandler.addF= ilterWithMapping(ServletHandler.java:965) [junit4:junit4] 2> =09=09at org.eclipse.jetty.servlet.ServletHandler.addF= ilterWithMapping(ServletHandler.java:923) [junit4:junit4] 2> =09=09at org.eclipse.jetty.servlet.ServletContextHandl= er.addFilter(ServletContextHandler.java:333) [junit4:junit4] 2> =09=09at org.apache.solr.client.solrj.embedded.JettySo= lrRunner$1.lifeCycleStarted(JettySolrRunner.java:173) [junit4:junit4] 2> =09=09at org.eclipse.jetty.util.component.AbstractLife= Cycle.setStarted(AbstractLifeCycle.java:169) [junit4:junit4] 2> =09=09at org.eclipse.jetty.util.component.AbstractLife= Cycle.start(AbstractLifeCycle.java:60) [junit4:junit4] 2> =09=09at org.apache.solr.client.solrj.embedded.JettySo= lrRunner.start(JettySolrRunner.java:225) [junit4:junit4] 2> =09=09at org.apache.solr.client.solrj.embedded.JettySo= lrRunner.start(JettySolrRunner.java:205) [junit4:junit4] 2> =09=09at org.apache.solr.BaseDistributedSearchTestCase= .createJetty(BaseDistributedSearchTestCase.java:262) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.AbstractFullDistribZkTe= stBase.createJettys(AbstractFullDistribZkTestBase.java:292) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.AbstractFullDistribZkTe= stBase.createServers(AbstractFullDistribZkTestBase.java:245) [junit4:junit4] 2> =09=09at org.apache.solr.BaseDistributedSearchTestCase= .testDistribSearch(BaseDistributedSearchTestCase.java:693) [junit4:junit4] 2> =09=09at sun.reflect.NativeMethodAccessorImpl.invoke0(= Native Method) [junit4:junit4] 2> =09=09at sun.reflect.NativeMethodAccessorImpl.invoke(N= ativeMethodAccessorImpl.java:57) [junit4:junit4] 2> =09=09at sun.reflect.DelegatingMethodAccessorImpl.invo= ke(DelegatingMethodAccessorImpl.java:43) [junit4:junit4] 2> =09=09at java.lang.reflect.Method.invoke(Method.java:6= 01) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner.invoke(RandomizedRunner.java:1559) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner.access$600(RandomizedRunner.java:79) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner$6.evaluate(RandomizedRunner.java:737) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner$7.evaluate(RandomizedRunner.java:773) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner$8.evaluate(RandomizedRunner.java:787) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.Syst= emPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleSetupTeardownC= hained$1.evaluate(TestRuleSetupTeardownChained.java:50) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleFieldCacheSani= ty$1.evaluate(TestRuleFieldCacheSanity.java:51) [junit4:junit4] 2> =09=09at org.apache.lucene.util.AbstractBeforeAfterRul= e$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.Syst= emPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleThreadAndTestN= ame$1.evaluate(TestRuleThreadAndTestName.java:48) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleIgnoreAfterMax= Failures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleMarkFailure$1.= evaluate(TestRuleMarkFailure.java:48) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.Stat= ementAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.ThreadLeak= Control$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.ThreadLeak= Control.forkTimeoutingTask(ThreadLeakControl.java:782) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.ThreadLeak= Control$3.evaluate(ThreadLeakControl.java:442) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner.runSingleTest(RandomizedRunner.java:746) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner$3.evaluate(RandomizedRunner.java:648) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner$4.evaluate(RandomizedRunner.java:682) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.Randomized= Runner$5.evaluate(RandomizedRunner.java:693) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.Stat= ementAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.Syst= emPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] 2> =09=09at org.apache.lucene.util.AbstractBeforeAfterRul= e$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleStoreClassName= $1.evaluate(TestRuleStoreClassName.java:42) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.Syst= emPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.NoSh= adowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsR= ule.java:39) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.NoSh= adowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsR= ule.java:39) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.Stat= ementAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleAssertionsRequ= ired$1.evaluate(TestRuleAssertionsRequired.java:43) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleMarkFailure$1.= evaluate(TestRuleMarkFailure.java:48) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleIgnoreAfterMax= Failures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] 2> =09=09at org.apache.lucene.util.TestRuleIgnoreTestSuit= es$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.rules.Stat= ementAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] 2> =09=09at com.carrotsearch.randomizedtesting.ThreadLeak= Control$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] 2> =09=09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2> =09 [junit4:junit4] 2> 70228 T1704 oass.SolrDispatchFilter.init user.dir=3DC:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test\J0 [junit4:junit4] 2> 70228 T1704 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 70228 T1704 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 70649 T1704 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 70654 T1704 oejs.AbstractConnector.doStart Started Sel= ectChannelConnector@0.0.0.0:54851 [junit4:junit4] 2> 70655 T1704 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 70656 T1704 oasc.SolrResourceLoader.locateSolrHome usi= ng system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basi= cDistributedZk2Test-jetty3-1351407772434 [junit4:junit4] 2> 70657 T1704 oasc.SolrResourceLoader. new SolrRes= ourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Bas= icDistributedZk2Test-jetty3-1351407772434\' [junit4:junit4] 2> 70713 T1704 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() [junit4:junit4] 2> 70714 T1704 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 70714 T1704 oasc.SolrResourceLoader.locateSolrHome usi= ng system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basi= cDistributedZk2Test-jetty3-1351407772434 [junit4:junit4] 2> 70715 T1704 oasc.CoreContainer$Initializer.initialize = looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Win= dows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZ= k2Test-jetty3-1351407772434\solr.xml [junit4:junit4] 2> 70716 T1704 oasc.CoreContainer. New CoreContaine= r 716668 [junit4:junit4] 2> 70716 T1704 oasc.CoreContainer.load Loading CoreContai= ner using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Win= dows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZ= k2Test-jetty3-1351407772434\' [junit4:junit4] 2> 70717 T1704 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-tru= nk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistri= butedZk2Test-jetty3-1351407772434\' [junit4:junit4] 2> 70753 T1704 oasc.CoreContainer.load Registering Log Li= stener [junit4:junit4] 2> 70780 T1704 oasc.CoreContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:54823/solr [junit4:junit4] 2> 70780 T1704 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:54823/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@96cd6d [junit4:junit4] 2> 70782 T1763 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:54823 [junit4:junit4] 2> 70782 T1704 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 70786 T1763 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to 127.0.0.1/127.0.0.1:54823, initiating sess= ion [junit4:junit4] 2> 70787 T1706 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /127.0.0.1:54856 [junit4:junit4] 2> 70787 T1706 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /127.0.0.1:54856 [junit4:junit4] 2> 70805 T1708 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x13aa62d10bf0006 with negotiated timeout 5000 for client /1= 27.0.0.1:54856 [junit4:junit4] 2> 70805 T1763 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server 127.0.0.1/127.0.0.1:54823, sessi= onid =3D 0x13aa62d10bf0006, negotiated timeout =3D 5000 [junit4:junit4] 2> 70806 T1764 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@96cd6d name:ZooKeeperConnectio= n Watcher:127.0.0.1:54823/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 70807 T1704 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 70809 T1709 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa62d10bf0006 type:cr= eate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 70811 T1709 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa62d10bf0006 type:cr= eate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 70814 T1704 oasc.ZkController.createEphemeralLiveNode = Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54851_solr [junit4:junit4] 2> 70815 T1709 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa62d10bf0006 type:de= lete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/live_nodes/127.0.0.1:54851_solr Error:KeeperErrorCode =3D NoNode fo= r /solr/live_nodes/127.0.0.1:54851_solr [junit4:junit4] 2> 70817 T1704 oascc.SolrZkClient.makePath makePath: /liv= e_nodes/127.0.0.1:54851_solr [junit4:junit4] 2> 70822 T1740 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (3) [junit4:junit4] 2> 70824 T1724 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 70825 T1724 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 70823 T1729 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 70825 T1729 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 70826 T1752 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 70826 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting socketTimeout to: 0 [junit4:junit4] 2> 70827 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting urlScheme to: http:// [junit4:junit4] 2> 70827 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting connTimeout to: 0 [junit4:junit4] 2> 70829 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 70829 T1740 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 70830 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting corePoolSize to: 0 [junit4:junit4] 2> 70830 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 70831 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 70831 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting sizeOfQueue to: -1 [junit4:junit4] 2> 70832 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting fairnessPolicy to: false [junit4:junit4] 2> 70832 T1704 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000= &socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 70850 T1704 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 70860 T1704 oasc.CoreContainer.create Creating SolrCor= e 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basi= cDistributedZk2Test-jetty3-1351407772434\collection1 [junit4:junit4] 2> 70861 T1704 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:collection1 [junit4:junit4] 2> 70862 T1704 oasc.ZkController.createCollectionZkNode C= ollection zkNode exists [junit4:junit4] 2> 70862 T1704 oasc.ZkController.readConfigName Load coll= ection config from:/collections/collection1 [junit4:junit4] 2> 70865 T1704 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-tru= nk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistri= butedZk2Test-jetty3-1351407772434\collection1\' [junit4:junit4] 2> 70868 T1704 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/so= lr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-je= tty3-1351407772434/collection1/lib/classes/' to classloader [junit4:junit4] 2> 70869 T1704 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/so= lr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-je= tty3-1351407772434/collection1/lib/README' to classloader [junit4:junit4] 2> 70936 T1704 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_50 [junit4:junit4] 2> 70995 T1704 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 70997 T1704 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 71003 T1704 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 71748 T1704 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 71759 T1704 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 71763 T1704 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 71781 T1704 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 71789 T1704 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 71797 T1704 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 71799 T1704 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 71802 T1704 oasc.SolrCore. [collection1] Opening= new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\= solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Tes= t-jetty3-1351407772434\collection1\, dataDir=3D.\org.apache.solr.cloud.Basi= cDistributedZk2Test-1351407702197/jetty3\ [junit4:junit4] 2> 71802 T1704 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@1128cc [junit4:junit4] 2> 71803 T1704 oasc.SolrCore.initDirectoryFactory solr.NR= TCachingDirectoryFactory [junit4:junit4] 2> 71805 T1704 oasc.CachingDirectoryFactory.injectLockFac= tory WARNING No lockType configured for NRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty3 lockFactory=3Dorg.apache.lucene.store.Native= FSLockFactory@b00277; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0) assuming 'sim= ple' [junit4:junit4] 2> 71806 T1704 oasc.CachingDirectoryFactory.get return ne= w directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\s= olr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test= -1351407702197\jetty3 forceNew:false [junit4:junit4] 2> 71807 T1704 oasc.CachingDirectoryFactory.close Releasi= ng directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty3 [junit4:junit4] 2> 71807 T1704 oasc.SolrCore.getNewIndexDir New index dir= ectory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZ= k2Test-1351407702197/jetty3\index/ [junit4:junit4] 2> 71809 T1704 oasc.SolrCore.initIndex WARNING [collectio= n1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1= 351407702197\jetty3\index' doesn't exist. Creating new index... [junit4:junit4] 2> 71809 T1704 oasc.CachingDirectoryFactory.get return ne= w directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\s= olr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test= -1351407702197\jetty3\index forceNew:false [junit4:junit4] 2> 71825 T1704 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty3\index lockFactory=3Dorg.apache.lucene.store.= NativeFSLockFactory@233061; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 71826 T1704 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1[segments_1] [junit4:junit4] 2> 71829 T1704 oasc.CachingDirectoryFactory.close Releasi= ng directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty3\index [junit4:junit4] 2> 71830 T1704 oasc.SolrCore.initWriters created xml: sol= r.XMLResponseWriter [junit4:junit4] 2> 71832 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 71833 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe-allfields" [junit4:junit4] 2> 71834 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 71835 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= uniq-fields" [junit4:junit4] 2> 71836 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 71836 T1704 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 71836 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 71837 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 71838 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 71839 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created dismax: solr.SearchHandler [junit4:junit4] 2> 71840 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 71841 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 71842 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 71843 T1704 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 71843 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 71844 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 71845 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 71846 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 71847 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH_Direct: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 71848 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 71849 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component= .SearchHandler [junit4:junit4] 2> 71850 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandle= r [junit4:junit4] 2> 71850 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 71852 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 71853 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 71871 T1704 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 71879 T1704 oasc.SolrCore.initDeprecatedSupport WARNIN= G solrconfig.xml uses deprecated , Please update your = config to use the ShowFileRequestHandler. [junit4:junit4] 2> 71880 T1704 oasc.SolrCore.initDeprecatedSupport WARNIN= G adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYM= S.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT= , CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 71883 T1704 oasc.CachingDirectoryFactory.close Releasi= ng directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty3 [junit4:junit4] 2> 71886 T1704 oass.SolrIndexSearcher. Opening Sear= cher@464737 main [junit4:junit4] 2> 71888 T1704 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 71888 T1704 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 71889 T1704 oashc.SpellCheckComponent.inform Initializ= ing spell checkers [junit4:junit4] 2> 71904 T1704 oass.DirectSolrSpellChecker.init init: {na= me=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLe= ngth=3D3} [junit4:junit4] 2> 71996 T1765 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@464737 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 72003 T1704 oasc.ZkController.publish numShards not fo= und on descriptor - reading it from system property [junit4:junit4] 2> 72236 T1725 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 72237 T1725 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54851_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54851/solr"} [junit4:junit4] 2> 72245 T1724 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 72245 T1764 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 72245 T1729 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 72246 T1740 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 72513 T1704 oasc.CoreContainer.register registering co= re: collection1 [junit4:junit4] 2> 72513 T1704 oasc.ZkController.register Register shard = - core:collection1 address:http://127.0.0.1:54851/solr shardId:shard1 [junit4:junit4] 2> 72518 T1704 oasc.ZkController.register We are http://1= 27.0.0.1:54851/solr/collection1/ and leader is http://127.0.0.1:54839/solr/= collection1/ [junit4:junit4] 2> 72519 T1704 oasc.ZkController.register No LogReplay ne= eded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:54851/solr [junit4:junit4] 2> 72519 T1704 oasc.ZkController.checkRecovery Core needs= to recover:collection1 [junit4:junit4] 2> 72519 T1704 oasu.DefaultSolrCoreState.doRecovery Runni= ng recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C181 name=3Dcollection1 org.apache.sol= r.core.SolrCore@81996d url=3Dhttp://127.0.0.1:54851/solr/collection1 node= =3D127.0.0.1:54851_solr [junit4:junit4] 2> 72521 T1766 C181 P54851 oasc.RecoveryStrategy.run Star= ting recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 72523 T1766 C181 P54851 oasc.RecoveryStrategy.doRecove= ry ###### startupVersions=3D[] [junit4:junit4] 2> 72523 T1766 C181 P54851 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [junit4:junit4] 2> 72523 T1704 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 72525 T1766 C181 P54851 oasc.RecoveryStrategy.doRecove= ry Attempting to PeerSync from http://127.0.0.1:54839/solr/collection1/ cor= e=3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 72526 T1766 C181 P54851 oasu.PeerSync.sync PeerSync: c= ore=3Dcollection1 url=3Dhttp://127.0.0.1:54851/solr START replicas=3D[http:= //127.0.0.1:54839/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 72526 T1704 oass.SolrDispatchFilter.init user.dir=3DC:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test\J0 [junit4:junit4] 2> 72526 T1704 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 72527 T1704 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 72526 T1766 C181 P54851 oasu.PeerSync.sync WARNING no = frame of reference to tell of we've missed updates [junit4:junit4] 2> 72529 T1766 C181 P54851 oasc.RecoveryStrategy.doRecove= ry PeerSync Recovery was not successful - trying replication. core=3Dcollec= tion1 [junit4:junit4] 2> 72530 T1766 C181 P54851 oasc.RecoveryStrategy.doRecove= ry Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 72530 T1766 C181 P54851 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C182 name=3Dcollection1 org.apache.sol= r.core.SolrCore@15a1d09 url=3Dhttp://127.0.0.1:54839/solr/collection1 node= =3D127.0.0.1:54839_solr [junit4:junit4] 2> 72550 T1738 C182 P54839 REQ /get {distrib=3Dfalse&qt= =3D/get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 72758 T1725 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 72759 T1725 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"recovering", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54851_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54851/solr"} [junit4:junit4] 2> 72775 T1729 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 72775 T1724 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 72775 T1740 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 72776 T1764 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 72968 T1704 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 72972 T1704 oejs.AbstractConnector.doStart Started Sel= ectChannelConnector@0.0.0.0:54859 [junit4:junit4] 2> 72974 T1704 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 72975 T1704 oasc.SolrResourceLoader.locateSolrHome usi= ng system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basi= cDistributedZk2Test-jetty4-1351407774736 [junit4:junit4] 2> 72976 T1704 oasc.SolrResourceLoader. new SolrRes= ourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Bas= icDistributedZk2Test-jetty4-1351407774736\' [junit4:junit4] 2> 73031 T1704 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() [junit4:junit4] 2> 73032 T1704 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 73032 T1704 oasc.SolrResourceLoader.locateSolrHome usi= ng system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basi= cDistributedZk2Test-jetty4-1351407774736 [junit4:junit4] 2> 73032 T1704 oasc.CoreContainer$Initializer.initialize = looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Win= dows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZ= k2Test-jetty4-1351407774736\solr.xml [junit4:junit4] 2> 73033 T1704 oasc.CoreContainer. New CoreContaine= r 21756683 [junit4:junit4] 2> 73033 T1704 oasc.CoreContainer.load Loading CoreContai= ner using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Win= dows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZ= k2Test-jetty4-1351407774736\' [junit4:junit4] 2> 73034 T1704 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-tru= nk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistri= butedZk2Test-jetty4-1351407774736\' [junit4:junit4] 2> 73071 T1704 oasc.CoreContainer.load Registering Log Li= stener [junit4:junit4] 2> 73098 T1704 oasc.CoreContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:54823/solr [junit4:junit4] 2> 73099 T1704 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:54823/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1b37aa9 [junit4:junit4] 2> 73100 T1777 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:54823 [junit4:junit4] 2> 73100 T1704 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 73103 T1777 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to 127.0.0.1/127.0.0.1:54823, initiating sess= ion [junit4:junit4] 2> 73103 T1706 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /127.0.0.1:54864 [junit4:junit4] 2> 73103 T1706 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /127.0.0.1:54864 [junit4:junit4] 2> 73111 T1708 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x13aa62d10bf0007 with negotiated timeout 5000 for client /1= 27.0.0.1:54864 [junit4:junit4] 2> 73112 T1777 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server 127.0.0.1/127.0.0.1:54823, sessi= onid =3D 0x13aa62d10bf0007, negotiated timeout =3D 5000 [junit4:junit4] 2> 73112 T1778 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@1b37aa9 name:ZooKeeperConnecti= on Watcher:127.0.0.1:54823/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 73113 T1704 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 73114 T1709 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa62d10bf0007 type:cr= eate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 73122 T1709 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa62d10bf0007 type:cr= eate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 73128 T1704 oasc.ZkController.createEphemeralLiveNode = Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54859_solr [junit4:junit4] 2> 73128 T1709 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa62d10bf0007 type:de= lete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/live_nodes/127.0.0.1:54859_solr Error:KeeperErrorCode =3D NoNode fo= r /solr/live_nodes/127.0.0.1:54859_solr [junit4:junit4] 2> 73130 T1704 oascc.SolrZkClient.makePath makePath: /liv= e_nodes/127.0.0.1:54859_solr [junit4:junit4] 2> 73135 T1740 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 73135 T1729 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 73136 T1729 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 73136 T1724 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 73136 T1724 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 73137 T1752 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 73137 T1764 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 73137 T1764 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 73138 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting socketTimeout to: 0 [junit4:junit4] 2> 73139 T1740 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 73139 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting urlScheme to: http:// [junit4:junit4] 2> 73140 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting connTimeout to: 0 [junit4:junit4] 2> 73141 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 73141 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting corePoolSize to: 0 [junit4:junit4] 2> 73142 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 73142 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 73143 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting sizeOfQueue to: -1 [junit4:junit4] 2> 73144 T1704 oashc.HttpShardHandlerFactory.getParameter= Setting fairnessPolicy to: false [junit4:junit4] 2> 73144 T1704 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000= &socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 73162 T1704 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 73169 T1704 oasc.CoreContainer.create Creating SolrCor= e 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-S= olr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Basi= cDistributedZk2Test-jetty4-1351407774736\collection1 [junit4:junit4] 2> 73169 T1704 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:collection1 [junit4:junit4] 2> 73171 T1704 oasc.ZkController.createCollectionZkNode C= ollection zkNode exists [junit4:junit4] 2> 73171 T1704 oasc.ZkController.readConfigName Load coll= ection config from:/collections/collection1 [junit4:junit4] 2> 73174 T1704 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-tru= nk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistri= butedZk2Test-jetty4-1351407774736\collection1\' [junit4:junit4] 2> 73176 T1704 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/so= lr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-je= tty4-1351407774736/collection1/lib/classes/' to classloader [junit4:junit4] 2> 73178 T1704 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/so= lr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-je= tty4-1351407774736/collection1/lib/README' to classloader [junit4:junit4] 2> 73232 T1704 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_50 [junit4:junit4] 2> 73346 T1704 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 73349 T1704 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 73356 T1704 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 74115 T1704 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 74125 T1704 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 74128 T1704 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 74144 T1704 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 74155 T1704 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 74161 T1704 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 74163 T1704 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 74164 T1704 oasc.SolrCore. [collection1] Opening= new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\= solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Tes= t-jetty4-1351407774736\collection1\, dataDir=3D.\org.apache.solr.cloud.Basi= cDistributedZk2Test-1351407702197/jetty4\ [junit4:junit4] 2> 74165 T1704 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@1128cc [junit4:junit4] 2> 74165 T1704 oasc.SolrCore.initDirectoryFactory solr.NR= TCachingDirectoryFactory [junit4:junit4] 2> 74166 T1704 oasc.CachingDirectoryFactory.injectLockFac= tory WARNING No lockType configured for NRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty4 lockFactory=3Dorg.apache.lucene.store.Native= FSLockFactory@ec1a43; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0) assuming 'sim= ple' [junit4:junit4] 2> 74166 T1704 oasc.CachingDirectoryFactory.get return ne= w directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\s= olr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test= -1351407702197\jetty4 forceNew:false [junit4:junit4] 2> 74167 T1704 oasc.CachingDirectoryFactory.close Releasi= ng directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty4 [junit4:junit4] 2> 74167 T1704 oasc.SolrCore.getNewIndexDir New index dir= ectory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZ= k2Test-1351407702197/jetty4\index/ [junit4:junit4] 2> 74168 T1704 oasc.SolrCore.initIndex WARNING [collectio= n1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1= 351407702197\jetty4\index' doesn't exist. Creating new index... [junit4:junit4] 2> 74168 T1704 oasc.CachingDirectoryFactory.get return ne= w directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\s= olr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test= -1351407702197\jetty4\index forceNew:false [junit4:junit4] 2> 74180 T1704 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty4\index lockFactory=3Dorg.apache.lucene.store.= NativeFSLockFactory@185f784; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 74181 T1704 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1[segments_1] [junit4:junit4] 2> 74184 T1704 oasc.CachingDirectoryFactory.close Releasi= ng directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty4\index [junit4:junit4] 2> 74184 T1704 oasc.SolrCore.initWriters created xml: sol= r.XMLResponseWriter [junit4:junit4] 2> 74186 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 74187 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe-allfields" [junit4:junit4] 2> 74187 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 74188 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= uniq-fields" [junit4:junit4] 2> 74189 T1704 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 74190 T1704 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 74191 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 74191 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 74192 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 74193 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created dismax: solr.SearchHandler [junit4:junit4] 2> 74194 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 74195 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 74196 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 74197 T1704 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 74198 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 74198 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 74199 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 74200 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 74201 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH_Direct: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 74202 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 74202 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component= .SearchHandler [junit4:junit4] 2> 74203 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandle= r [junit4:junit4] 2> 74203 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 74204 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 74205 T1704 oasc.RequestHandlers.initHandlersFromConfi= g created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 74215 T1704 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 74220 T1704 oasc.SolrCore.initDeprecatedSupport WARNIN= G solrconfig.xml uses deprecated , Please update your = config to use the ShowFileRequestHandler. [junit4:junit4] 2> 74222 T1704 oasc.SolrCore.initDeprecatedSupport WARNIN= G adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYM= S.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT= , CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 74225 T1704 oasc.CachingDirectoryFactory.close Releasi= ng directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty4 [junit4:junit4] 2> 74227 T1704 oass.SolrIndexSearcher. Opening Sear= cher@15458c6 main [junit4:junit4] 2> 74229 T1704 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 74230 T1704 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 74231 T1704 oashc.SpellCheckComponent.inform Initializ= ing spell checkers [junit4:junit4] 2> 74241 T1704 oass.DirectSolrSpellChecker.init init: {na= me=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLe= ngth=3D3} [junit4:junit4] 2> 74296 T1779 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@15458c6 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 74301 T1704 oasc.ZkController.publish numShards not fo= und on descriptor - reading it from system property [junit4:junit4] 2> 74790 T1725 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 74792 T1725 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54859_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54859/solr"} [junit4:junit4] 2> 74843 T1724 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 74843 T1729 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 74844 T1764 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 74844 T1778 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 74844 T1740 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75303 T1704 oasc.CoreContainer.register registering co= re: collection1 [junit4:junit4] 2> 75303 T1704 oasc.ZkController.register Register shard = - core:collection1 address:http://127.0.0.1:54859/solr shardId:shard2 [junit4:junit4] 2> 75305 T1704 oascc.SolrZkClient.makePath makePath: /col= lections/collection1/leader_elect/shard2/election [junit4:junit4] 2> 75324 T1709 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa62d10bf0007 type:de= lete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode =3D N= oNode for /solr/collections/collection1/leaders/shard2 [junit4:junit4] 2> 75326 T1704 oasc.ShardLeaderElectionContext.runLeaderP= rocess Running the leader process. [junit4:junit4] 2> 75327 T1709 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa62d10bf0007 type:cr= eate cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 75342 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D180000 [junit4:junit4] 2> 75353 T1725 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 75365 T1729 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75365 T1724 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75365 T1740 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75365 T1778 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75365 T1764 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> C181_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:54851_solr, base_url=3Dhttp://1= 27.0.0.1:54851/solr} [junit4:junit4] 2> 75556 T1766 C181 P54851 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 75556 T1766 C181 P54851 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 75556 T1766 C181 P54851 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:54839/solr/collection1/. co= re=3Dcollection1 [junit4:junit4] 2> 75557 T1766 C181 P54851 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 75573 T1735 C182 P54839 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dfal= se,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 75575 T1735 C182 P54839 oasc.CachingDirectoryFactory.c= lose Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-= Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty1 [junit4:junit4] 2> 75580 T1735 C182 P54839 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty1\index lockFactory=3Dorg.apache.lucene.store.= NativeFSLockFactory@1dcd021; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 75581 T1735 C182 P54839 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1[segments_1] [junit4:junit4] 2> 75581 T1735 C182 P54839 oasc.CachingDirectoryFactory.c= lose Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-= Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty1 [junit4:junit4] 2> 75582 T1735 C182 P54839 oass.SolrIndexSearcher. = Opening Searcher@1d18f21 realtime [junit4:junit4] 2> 75582 T1735 C182 P54839 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 75582 T1735 C182 P54839 /update {waitSearcher=3Dtrue&o= penSearcher=3Dfalse&commit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&soft= Commit=3Dfalse&version=3D2} {commit=3D} 0 9 [junit4:junit4] 2> 75583 T1766 C181 P54851 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 75583 T1766 C181 P54851 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 75586 T1737 C182 P54839 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 75586 T1737 C182 P54839 REQ /replication {command=3Din= dexversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 75586 T1766 C181 P54851 oasc.CachingDirectoryFactory.c= lose Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-= Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty3 [junit4:junit4] 2> 75591 T1766 C181 P54851 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty3\index lockFactory=3Dorg.apache.lucene.store.= NativeFSLockFactory@233061; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 75592 T1766 C181 P54851 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1[segments_1] [junit4:junit4] 2> 75593 T1766 C181 P54851 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 75600 T1766 C181 P54851 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty3\index lockFactory=3Dorg.apache.lucene.store.= NativeFSLockFactory@233061; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trun= k-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty3\index lockFactory=3Dorg.apache.lucene.store.= NativeFSLockFactory@233061; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0),segFN= =3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 75601 T1766 C181 P54851 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2[segments_2] [junit4:junit4] 2> 75603 T1766 C181 P54851 oasc.CachingDirectoryFactory.c= lose Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-= Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistribut= edZk2Test-1351407702197\jetty3 [junit4:junit4] 2> 75606 T1766 C181 P54851 oass.SolrIndexSearcher. = Opening Searcher@2be674 main [junit4:junit4] 2> 75606 T1766 C181 P54851 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 75607 T1765 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@2be674 main{StandardDirectoryReader(seg= ments_2:2)} [junit4:junit4] 2> 75607 T1765 oasc.CachingDirectoryFactory.close Releasi= ng directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr= \build\solr-core\test\J0\.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51407702197\jetty3\index [junit4:junit4] 2> 75608 T1766 C181 P54851 oasc.RecoveryStrategy.replay N= o replay needed. core=3Dcollection1 [junit4:junit4] 2> 75608 T1766 C181 P54851 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 75608 T1766 C181 P54851 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [junit4:junit4] 2> 75610 T1766 C181 P54851 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 75842 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D179500 [junit4:junit4] 2> 75874 T1725 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 75875 T1725 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "core":"collection1", [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:54851_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:54851/solr"} [junit4:junit4] 2> 75883 T1729 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75883 T1764 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75883 T1778 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75885 T1740 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 75883 T1724 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 76343 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D178999 [junit4:junit4] 2> 76845 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D178497 [junit4:junit4] 2> 77346 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D177996 [junit4:junit4] 2> 77847 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D177495 [junit4:junit4] 2> 78347 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D176995 [junit4:junit4] 2> 78849 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D176493 [junit4:junit4] 2> 79351 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D175991 [junit4:junit4] 2> 79851 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D175491 [junit4:junit4] 2> 80351 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D174991 [junit4:junit4] 2> 80852 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D174490 [junit4:junit4] 2> 81353 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D173989 [junit4:junit4] 2> 81854 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D173488 [junit4:junit4] 2> 82361 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D172981 [junit4:junit4] 2> 82913 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D172429 [junit4:junit4] 2> 83464 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D171878 [junit4:junit4] 2> 84016 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D171326 [junit4:junit4] 2> 84567 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D170775 [junit4:junit4] 2> 85119 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D170223 [junit4:junit4] 2> 85670 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D169672 [junit4:junit4] 2> 86220 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D169122 [junit4:junit4] 2> 86772 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D168570 [junit4:junit4] 2> 87323 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D168019 [junit4:junit4] 2> 87873 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D167469 [junit4:junit4] 2> 88424 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D166918 [junit4:junit4] 2> 88976 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D166366 [junit4:junit4] 2> 89528 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D165814 [junit4:junit4] 2> 90079 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D165263 [junit4:junit4] 2> 90630 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D164712 [junit4:junit4] 2> 91183 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D164159 [junit4:junit4] 2> 91736 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D163606 [junit4:junit4] 2> 92286 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D163056 [junit4:junit4] 2> 92837 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D162505 [junit4:junit4] 2> 93378 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D161964 [junit4:junit4] 2> 93874 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D161468 [junit4:junit4] 2> 94371 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D160971 [junit4:junit4] 2> 94867 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D160475 [junit4:junit4] 2> 95363 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D159979 [junit4:junit4] 2> 95859 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D159483 [junit4:junit4] 2> 96355 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D158987 [junit4:junit4] 2> 96851 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D158491 [junit4:junit4] 2> 97347 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D157995 [junit4:junit4] 2> 97843 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D157499 [junit4:junit4] 2> 98339 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D157003 [junit4:junit4] 2> 98835 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D156507 [junit4:junit4] 2> 99331 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D156011 [junit4:junit4] 2> 99827 T1704 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D155515 [junit4:junit4] 2> 100323 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D155019 [junit4:junit4] 2> 100820 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D154522 [junit4:junit4] 2> 101316 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D154026 [junit4:junit4] 2> 101812 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D153530 [junit4:junit4] 2> 102308 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D153034 [junit4:junit4] 2> 102804 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D152538 [junit4:junit4] 2> 103288 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D152054 [junit4:junit4] 2> 103735 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D151607 [junit4:junit4] 2> 104182 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D151160 [junit4:junit4] 2> 104629 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D150713 [junit4:junit4] 2> 105074 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D150268 [junit4:junit4] 2> 105520 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D149822 [junit4:junit4] 2> 105966 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D149376 [junit4:junit4] 2> 106413 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D148929 [junit4:junit4] 2> 106861 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D148481 [junit4:junit4] 2> 107307 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D148035 [junit4:junit4] 2> 107753 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D147589 [junit4:junit4] 2> 108199 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D147143 [junit4:junit4] 2> 108645 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D146697 [junit4:junit4] 2> 109093 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D146249 [junit4:junit4] 2> 109539 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D145803 [junit4:junit4] 2> 109985 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D145357 [junit4:junit4] 2> 110431 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D144911 [junit4:junit4] 2> 110877 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D144465 [junit4:junit4] 2> 111324 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D144018 [junit4:junit4] 2> 111770 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D143572 [junit4:junit4] 2> 112228 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D143114 [junit4:junit4] 2> 112719 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D142623 [junit4:junit4] 2> 113209 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D142133 [junit4:junit4] 2> 113701 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D141641 [junit4:junit4] 2> 114191 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D141151 [junit4:junit4] 2> 114683 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D140659 [junit4:junit4] 2> 115173 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D140169 [junit4:junit4] 2> 115664 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D139678 [junit4:junit4] 2> 116155 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D139187 [junit4:junit4] 2> 116646 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D138696 [junit4:junit4] 2> 117137 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D138205 [junit4:junit4] 2> 117628 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D137714 [junit4:junit4] 2> 118119 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D137223 [junit4:junit4] 2> 118610 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D136732 [junit4:junit4] 2> 119101 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D136241 [junit4:junit4] 2> 119592 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D135750 [junit4:junit4] 2> 120082 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D135260 [junit4:junit4] 2> 120572 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D134770 [junit4:junit4] 2> 121064 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D134278 [junit4:junit4] 2> 121554 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D133788 [junit4:junit4] 2> 122058 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D133284 [junit4:junit4] 2> 122599 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D132743 [junit4:junit4] 2> 123139 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D132203 [junit4:junit4] 2> 123679 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D131663 [junit4:junit4] 2> 124219 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D131123 [junit4:junit4] 2> 124760 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D130582 [junit4:junit4] 2> 125300 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D130042 [junit4:junit4] 2> 125841 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D129501 [junit4:junit4] 2> 126382 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D128960 [junit4:junit4] 2> 126923 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D128419 [junit4:junit4] 2> 127463 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D127879 [junit4:junit4] 2> 128002 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D127340 [junit4:junit4] 2> 128541 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D126801 [junit4:junit4] 2> 129081 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D126261 [junit4:junit4] 2> 129621 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D125721 [junit4:junit4] 2> 130162 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D125180 [junit4:junit4] 2> 130703 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D124639 [junit4:junit4] 2> 131245 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D124097 [junit4:junit4] 2> 131784 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D123558 [junit4:junit4] 2> 132325 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D123017 [junit4:junit4] 2> 132884 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D122458 [junit4:junit4] 2> 133478 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D121864 [junit4:junit4] 2> 134072 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D121270 [junit4:junit4] 2> 134666 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D120676 [junit4:junit4] 2> 135259 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D120083 [junit4:junit4] 2> 135853 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D119489 [junit4:junit4] 2> 136447 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D118895 [junit4:junit4] 2> 137040 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D118302 [junit4:junit4] 2> 137634 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D117708 [junit4:junit4] 2> 138227 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D117115 [junit4:junit4] 2> 138820 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D116522 [junit4:junit4] 2> 139416 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D115926 [junit4:junit4] 2> 140009 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D115333 [junit4:junit4] 2> 140603 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D114739 [junit4:junit4] 2> 141198 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D114144 [junit4:junit4] 2> 141791 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D113551 [junit4:junit4] 2> 142386 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D112956 [junit4:junit4] 2> 142979 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D112363 [junit4:junit4] 2> 143573 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D111769 [junit4:junit4] 2> 144167 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D111175 [junit4:junit4] 2> 144740 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D110602 [junit4:junit4] 2> 145274 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D110068 [junit4:junit4] 2> 145809 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D109533 [junit4:junit4] 2> 146344 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D108998 [junit4:junit4] 2> 146877 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D108465 [junit4:junit4] 2> 147412 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D107930 [junit4:junit4] 2> 147947 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D107395 [junit4:junit4] 2> 148482 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D106860 [junit4:junit4] 2> 149017 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D106325 [junit4:junit4] 2> 149552 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D105790 [junit4:junit4] 2> 150087 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D105255 [junit4:junit4] 2> 150621 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D104721 [junit4:junit4] 2> 151156 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D104186 [junit4:junit4] 2> 151691 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D103651 [junit4:junit4] 2> 152225 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D103117 [junit4:junit4] 2> 152760 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D102582 [junit4:junit4] 2> 153295 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D102047 [junit4:junit4] 2> 153829 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D101513 [junit4:junit4] 2> 154365 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D100977 [junit4:junit4] 2> 154900 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D100442 [junit4:junit4] 2> 155413 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D99929 [junit4:junit4] 2> 155894 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D99448 [junit4:junit4] 2> 156375 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D98967 [junit4:junit4] 2> 156855 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D98487 [junit4:junit4] 2> 157337 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D98005 [junit4:junit4] 2> 157817 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D97525 [junit4:junit4] 2> 158299 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D97043 [junit4:junit4] 2> 158780 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D96562 [junit4:junit4] 2> 159262 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D96080 [junit4:junit4] 2> 159743 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D95599 [junit4:junit4] 2> 160223 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D95119 [junit4:junit4] 2> 160704 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D94638 [junit4:junit4] 2> 161186 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D94156 [junit4:junit4] 2> 161666 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D93676 [junit4:junit4] 2> 162147 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D93195 [junit4:junit4] 2> 162629 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D92713 [junit4:junit4] 2> 163110 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D92232 [junit4:junit4] 2> 163592 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D91750 [junit4:junit4] 2> 164074 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D91268 [junit4:junit4] 2> 164555 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D90787 [junit4:junit4] 2> 165015 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D90327 [junit4:junit4] 2> 165449 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D89893 [junit4:junit4] 2> 165883 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D89459 [junit4:junit4] 2> 166316 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D89026 [junit4:junit4] 2> 166750 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D88592 [junit4:junit4] 2> 167183 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D88159 [junit4:junit4] 2> 167616 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D87726 [junit4:junit4] 2> 168050 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D87292 [junit4:junit4] 2> 168482 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D86860 [junit4:junit4] 2> 168915 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D86427 [junit4:junit4] 2> 169348 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D85994 [junit4:junit4] 2> 169781 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D85561 [junit4:junit4] 2> 170214 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D85128 [junit4:junit4] 2> 170648 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D84694 [junit4:junit4] 2> 171081 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D84261 [junit4:junit4] 2> 171515 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D83827 [junit4:junit4] 2> 171949 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D83393 [junit4:junit4] 2> 172381 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D82961 [junit4:junit4] 2> 172814 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D82528 [junit4:junit4] 2> 173247 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D82095 [junit4:junit4] 2> 173702 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D81640 [junit4:junit4] 2> 174179 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D81163 [junit4:junit4] 2> 174656 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D80686 [junit4:junit4] 2> 175133 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D80209 [junit4:junit4] 2> 175610 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D79732 [junit4:junit4] 2> 176087 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D79255 [junit4:junit4] 2> 176566 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D78776 [junit4:junit4] 2> 177041 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D78301 [junit4:junit4] 2> 177517 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D77825 [junit4:junit4] 2> 177993 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D77349 [junit4:junit4] 2> 178469 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D76873 [junit4:junit4] 2> 178945 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D76397 [junit4:junit4] 2> 179421 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D75921 [junit4:junit4] 2> 179898 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D75444 [junit4:junit4] 2> 180374 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D74968 [junit4:junit4] 2> 180851 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D74491 [junit4:junit4] 2> 181327 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D74015 [junit4:junit4] 2> 181802 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D73540 [junit4:junit4] 2> 182279 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D73063 [junit4:junit4] 2> 182755 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D72587 [junit4:junit4] 2> 183257 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D72085 [junit4:junit4] 2> 183780 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D71562 [junit4:junit4] 2> 184303 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D71039 [junit4:junit4] 2> 184827 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D70515 [junit4:junit4] 2> 185351 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D69991 [junit4:junit4] 2> 185875 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D69467 [junit4:junit4] 2> 186401 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D68941 [junit4:junit4] 2> 186925 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D68417 [junit4:junit4] 2> 187449 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D67893 [junit4:junit4] 2> 187972 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D67370 [junit4:junit4] 2> 188496 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D66846 [junit4:junit4] 2> 189020 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D66322 [junit4:junit4] 2> 189544 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D65798 [junit4:junit4] 2> 190068 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D65274 [junit4:junit4] 2> 190592 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D64750 [junit4:junit4] 2> 191116 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D64226 [junit4:junit4] 2> 191640 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D63702 [junit4:junit4] 2> 192165 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D63177 [junit4:junit4] 2> 192688 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D62654 [junit4:junit4] 2> 193213 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D62129 [junit4:junit4] 2> 193766 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D61576 [junit4:junit4] 2> 194343 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D60999 [junit4:junit4] 2> 194919 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D60423 [junit4:junit4] 2> 195496 T1704 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D59846 [junit4:junit4] 2> 196072 T1704 oasc.ShardLeaderElectionContex [...truncated too long message...] mat(name=3DLucene41WithOrds), multiDefault=3DPostingsFormat(name=3DLucene41= WithOrds), n_tf1=3DPostingsFormat(name=3DLucene41WithOrds), n_dt1=3DPosting= sFormat(name=3DMemory doPackFST=3D true), n_ti1=3DPostingsFormat(name=3DDir= ect), range_facet_l=3DPostingsFormat(name=3DLucene41WithOrds), text=3DPosti= ngsFormat(name=3DMemory doPackFST=3D false), _version_=3DPostingsFormat(nam= e=3DMemory doPackFST=3D true), a_t=3DPostingsFormat(name=3DLucene41WithOrds= ), n_tdt1=3DPostingsFormat(name=3DMemory doPackFST=3D true), other_tl1=3DPo= stingsFormat(name=3DMemory doPackFST=3D true), n_l1=3DPostingsFormat(name= =3DLucene41WithOrds), a_si=3DPostingsFormat(name=3DMemory doPackFST=3D true= )}, sim=3DRandomSimilarityProvider(queryNorm=3Dfalse,coord=3Dno): {}, local= e=3Dpt_PT, timezone=3DSystemV/MST7 [junit4:junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_07 (3= 2-bit)/cpus=3D2,threads=3D2,free=3D72447216,total=3D202506240 [junit4:junit4] 2> NOTE: All tests run in this JVM: [TestSystemIdResolver= , AnalysisAfterCoreReloadTest, UpdateParamsTest, OutputWriterTest, TestXInc= ludeConfig, SearchHandlerTest, TestPhraseSuggestions, TestSolrDeletionPolic= y2, TestPluginEnable, DocumentBuilderTest, ClusterStateUpdateTest, TestColl= ationField, CircularListTest, TestStressLucene, TermVectorComponentDistribu= tedTest, FastVectorHighlighterTest, BadComponentTest, XsltUpdateRequestHand= lerTest, TestOmitPositions, SOLR749Test, DOMUtilTest, UpdateRequestProcesso= rFactoryTest, FullSolrCloudDistribCmdsTest, TestReload, TestNumberUtils, Sp= atialFilterTest, OpenExchangeRatesOrgProviderTest, FieldAnalysisRequestHand= lerTest, TermsComponentTest, DirectUpdateHandlerTest, RequiredFieldsTest, D= irectUpdateHandlerOptimizeTest, SpellPossibilityIteratorTest, TestSurroundQ= ueryParser, ReturnFieldsTest, LukeRequestHandlerTest, SimpleFacetsTest, NoC= acheHeaderTest, TestSort, NumericFieldsTest, TestJoin, SpellingQueryConvert= erTest, ZkSolrClientTest, TestHashPartitioner, PathHierarchyTokenizerFactor= yTest, ZkNodePropsTest, TestCodecSupport, TestSearchPerf, TestReversedWildc= ardFilterFactory, TestIndexSearcher, TestLMJelinekMercerSimilarityFactory, = ClusterStateTest, DisMaxRequestHandlerTest, HighlighterConfigTest, DefaultV= alueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestTri= e, HighlighterTest, TestSuggestSpellingConverter, BasicDistributedZkTest, T= estWriterPerf, TestRecovery, IndexSchemaRuntimeFieldTest, XmlUpdateRequestH= andlerTest, TestUpdate, TestFaceting, DebugComponentTest, TestExtendedDisma= xParser, AutoCommitTest, PrimitiveFieldTypeTest, SuggesterTest, CSVRequestH= andlerTest, TestStressReorder, TestMergePolicyConfig, TestDefaultSimilarity= Factory, TestFastLRUCache, StandardRequestHandlerTest, TestBadConfig, Conve= rtedLegacyTest, TestValueSourceCache, TestSolrIndexConfig, PolyFieldTest, S= yncSliceTest, LegacyHTMLStripCharFilterTest, TestPseudoReturnFields, TestPH= PSerializedResponseWriter, TestFoldingMultitermQuery, SolrRequestParserTest= , ShowFileRequestHandlerTest, TimeZoneUtilsTest, ResourceLoaderTest, TestIB= SimilarityFactory, TestBM25SimilarityFactory, SampleTest, TestSolrJ, TestRe= plicationHandler, NotRequiredUniqueKeyTest, TestStressVersions, BasicDistri= butedZk2Test] [junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DBasicDist= ributedZk2Test -Dtests.seed=3D763A6F09518A4C24 -Dtests.slow=3Dtrue -Dtests.= locale=3Dpt_PT -Dtests.timezone=3DSystemV/MST7 -Dtests.file.encoding=3DCp12= 52 [junit4:junit4] ERROR 0.00s | BasicDistributedZk2Test (suite) <<< [junit4:junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrInd= exSearcher opens=3D11 closes=3D10 [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([763A6F09518A4= C24]:0) [junit4:junit4] > =09at org.junit.Assert.fail(Assert.java:93) [junit4:junit4] > =09at org.apache.solr.SolrTestCaseJ4.endTrackingSearch= ers(SolrTestCaseJ4.java:243) [junit4:junit4] > =09at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTe= stCaseJ4.java:97) [junit4:junit4] > =09at sun.reflect.GeneratedMethodAccessor47.invoke(Unk= nown Source) [junit4:junit4] > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(= DelegatingMethodAccessorImpl.java:43) [junit4:junit4] > =09at java.lang.reflect.Method.invoke(Method.java:601) [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$5.evaluate(RandomizedRunner.java:700) [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:722) [junit4:junit4] > Throwable #2: com.carrotsearch.randomizedtesting.Threa= dLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.Basic= DistributedZk2Test:=20 [junit4:junit4] > 1) Thread[id=3D1753, name=3DsearcherExecutor-1327-t= hread-1, state=3DWAITING, group=3DTGRP-BasicDistributedZk2Test] [junit4:junit4] > at sun.misc.Unsafe.park(Native Method) [junit4:junit4] > at java.util.concurrent.locks.LockSupport.park= (LockSupport.java:186) [junit4:junit4] > at java.util.concurrent.locks.AbstractQueuedSy= nchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) [junit4:junit4] > at java.util.concurrent.LinkedBlockingQueue.ta= ke(LinkedBlockingQueue.java:442) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.get= Task(ThreadPoolExecutor.java:1043) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.run= Worker(ThreadPoolExecutor.java:1103) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor$Wor= ker.run(ThreadPoolExecutor.java:603) [junit4:junit4] > at java.lang.Thread.run(Thread.java:722) [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([763A6F09518A4= C24]:0) [junit4:junit4] > Throwable #3: com.carrotsearch.randomizedtesting.Threa= dLeakError: There are still zombie threads that couldn't be terminated: [junit4:junit4] > 1) Thread[id=3D1753, name=3DsearcherExecutor-1327-t= hread-1, state=3DWAITING, group=3DTGRP-BasicDistributedZk2Test] [junit4:junit4] > at sun.misc.Unsafe.park(Native Method) [junit4:junit4] > at java.util.concurrent.locks.LockSupport.park= (LockSupport.java:186) [junit4:junit4] > at java.util.concurrent.locks.AbstractQueuedSy= nchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) [junit4:junit4] > at java.util.concurrent.LinkedBlockingQueue.ta= ke(LinkedBlockingQueue.java:442) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.get= Task(ThreadPoolExecutor.java:1043) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.run= Worker(ThreadPoolExecutor.java:1103) [junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor$Wor= ker.run(ThreadPoolExecutor.java:603) [junit4:junit4] > at java.lang.Thread.run(Thread.java:722) [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([763A6F09518A4= C24]:0) [junit4:junit4] Completed in 1023.84s, 1 test, 2 failures, 2 errors <<< FAI= LURES! [...truncated 394 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:335: Th= e following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The= following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:17= 8: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\module-bui= ld.xml:61: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-bui= ld.xml:1159: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-bui= ld.xml:827: There were test failures: 232 suites, 961 tests, 3 suite-level = errors, 1 failure, 515 ignored Total time: 49 minutes 41 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Description set: Java: 32bit/jdk1.7.0_07 -client -XX:+UseConcMarkSweepGC Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_125_1135944167.1351408770303 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_125_1135944167.1351408770303--