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 E1930D90C for ; Sun, 28 Oct 2012 14:49:37 +0000 (UTC) Received: (qmail 63640 invoked by uid 500); 28 Oct 2012 14:49:36 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 63597 invoked by uid 500); 28 Oct 2012 14:49:36 -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 63584 invoked by uid 99); 28 Oct 2012 14:49:35 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 28 Oct 2012 14:49:35 +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 (athena.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 14:49:27 +0000 Received: from serv1 (localhost.localdomain [127.0.0.1]) by mail.sd-datasolutions.de (Postfix) with ESMTP id F2C1E14AA03D for ; Sun, 28 Oct 2012 14:49:04 +0000 (UTC) Date: Sun, 28 Oct 2012 14:48:48 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <905971141.134.1351435744984.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <129130909.132.1351429089212.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <129130909.132.1351429089212.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_07) - Build # 1341 - Still Failing! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_133_107448049.1351435728292" X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_133_107448049.1351435728292 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows/134= 1/ Java: 32bit/jdk1.7.0_07 -client -XX:+UseSerialGC 4 tests failed. 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([AD1EE9BD55A316B6]: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.GeneratedMethodAccessor21.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=3D3508, name=3DsearcherExecutor-2237-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=3D3508, name=3DsearcherExecutor-2237-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([AD1EE9BD55A316B6]: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= =3D3508, name=3DsearcherExecutor-2237-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=3D3508, name=3DsearcherExecutor-2237-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([AD1EE9BD55A316B6]:0) FAILED: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch Error Message: There are still nodes recoverying Stack Trace: java.lang.AssertionError: There are still nodes recoverying =09at __randomizedtesting.SeedInfo.seed([AD1EE9BD55A316B6:2CF867A522FC768A]= :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) Build Log: [...truncated 9042 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test [junit4:junit4] 2> 9 T3459 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-1351434686335 [junit4:junit4] 2> 9 T3459 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 9 T3460 oazs.ZooKeeperServer.setTickTime tickTime set = to 1000 [junit4:junit4] 2> 18 T3460 oazs.NIOServerCnxn$Factory. binding to = port 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 18 T3460 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 73 T3459 oasc.ZkTestServer.run start zk server on port= :56385 [junit4:junit4] 2> 73 T3459 oaz.ZooKeeper. Initiating client connec= tion, connectString=3D127.0.0.1:56385 sessionTimeout=3D10000 watcher=3Dorg.= apache.solr.common.cloud.ConnectionManager@16bd0a1 [junit4:junit4] 2> 73 T3465 oaz.ClientCnxn$SendThread.startConnect Openin= g socket connection to server /127.0.0.1:56385 [junit4:junit4] 2> 73 T3459 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4:junit4] 2> 73 T3465 oaz.ClientCnxn$SendThread.primeConnection Soc= ket connection established to 127.0.0.1/127.0.0.1:56385, initiating session [junit4:junit4] 2> 73 T3461 oazs.NIOServerCnxn$Factory.run Accepted socke= t connection from /127.0.0.1:56388 [junit4:junit4] 2> 73 T3461 oazs.NIOServerCnxn.readConnectRequest Client = attempting to establish new session at /127.0.0.1:56388 [junit4:junit4] 2> 73 T3463 oazsp.FileTxnLog.append Creating new log file= : log.1 [junit4:junit4] 2> 82 T3463 oazs.NIOServerCnxn.finishSessionInit Establis= hed session 0x13aa7c8cf880000 with negotiated timeout 10000 for client /127= .0.0.1:56388 [junit4:junit4] 2> 82 T3465 oaz.ClientCnxn$SendThread.readConnectResult S= ession establishment complete on server 127.0.0.1/127.0.0.1:56385, sessioni= d =3D 0x13aa7c8cf880000, negotiated timeout =3D 10000 [junit4:junit4] 2> 82 T3466 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@16bd0a1 name:ZooKeeperConnection = Watcher:127.0.0.1:56385 got event WatchedEvent state:SyncConnected type:Non= e path:null path:null type:None [junit4:junit4] 2> 82 T3459 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4:junit4] 2> 82 T3459 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 100 T3464 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x13aa7c8cf880000 [junit4:junit4] 2> 118 T3459 oaz.ZooKeeper.close Session: 0x13aa7c8cf8800= 00 closed [junit4:junit4] 2> 118 T3461 oazs.NIOServerCnxn.doIO WARNING EndOfStreamE= xception: Unable to read additional data from client sessionid 0x13aa7c8cf8= 80000, likely client has closed socket [junit4:junit4] 2> 118 T3459 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:56385/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1b4dfe9 [junit4:junit4] 2> 118 T3461 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:56388 which had sessionid 0x13aa7c8cf880000 [junit4:junit4] 2> 118 T3466 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 118 T3467 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:56385 [junit4:junit4] 2> 118 T3459 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 118 T3467 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to 127.0.0.1/127.0.0.1:56385, initiating sessio= n [junit4:junit4] 2> 118 T3461 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /127.0.0.1:56391 [junit4:junit4] 2> 118 T3461 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /127.0.0.1:56391 [junit4:junit4] 2> 136 T3463 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x13aa7c8cf880001 with negotiated timeout 10000 for client /12= 7.0.0.1:56391 [junit4:junit4] 2> 136 T3467 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server 127.0.0.1/127.0.0.1:56385, session= id =3D 0x13aa7c8cf880001, negotiated timeout =3D 10000 [junit4:junit4] 2> 136 T3468 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1b4dfe9 name:ZooKeeperConnection= Watcher:127.0.0.1:56385/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 136 T3459 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 136 T3459 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4:junit4] 2> 163 T3459 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4:junit4] 2> 182 T3459 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4:junit4] 2> 200 T3459 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4:junit4] 2> 218 T3459 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> 218 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 263 T3459 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> 263 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 272 T3459 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> 272 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 281 T3459 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> 281 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 300 T3459 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> 300 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 318 T3459 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> 318 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 336 T3459 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> 336 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 345 T3459 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> 345 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 363 T3459 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> 363 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 381 T3459 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> 381 T3459 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 400 T3464 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x13aa7c8cf880001 [junit4:junit4] 2> 418 T3459 oaz.ZooKeeper.close Session: 0x13aa7c8cf8800= 01 closed [junit4:junit4] 2> 418 T3468 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 418 T3461 oazs.NIOServerCnxn.doIO WARNING EndOfStreamE= xception: Unable to read additional data from client sessionid 0x13aa7c8cf8= 80001, likely client has closed socket [junit4:junit4] 2> 418 T3461 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:56391 which had sessionid 0x13aa7c8cf880001 [junit4:junit4] 2> 581 T3459 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 581 T3459 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@0.0.0.0:56392 [junit4:junit4] 2> 581 T3459 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 581 T3459 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-1351434686744 [junit4:junit4] 2> 581 T3459 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-1351434686744\' [junit4:junit4] 2> 608 T3459 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 608 T3459 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 608 T3459 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-1351434686744 [junit4:junit4] 2> 608 T3459 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-1351434686744\solr.xml [junit4:junit4] 2> 608 T3459 oasc.CoreContainer. New CoreContainer = 33201682 [junit4:junit4] 2> 608 T3459 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-1351434686744\' [junit4:junit4] 2> 608 T3459 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-1351434686744\' [junit4:junit4] 2> 627 T3459 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 636 T3459 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:56385/solr [junit4:junit4] 2> 636 T3459 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:56385/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@15b04b2 [junit4:junit4] 2> 636 T3478 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:56385 [junit4:junit4] 2> 636 T3459 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 636 T3478 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to 127.0.0.1/127.0.0.1:56385, initiating sessio= n [junit4:junit4] 2> 636 T3461 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /127.0.0.1:56397 [junit4:junit4] 2> 636 T3461 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /127.0.0.1:56397 [junit4:junit4] 2> 645 T3463 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x13aa7c8cf880002 with negotiated timeout 5000 for client /127= .0.0.1:56397 [junit4:junit4] 2> 645 T3478 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server 127.0.0.1/127.0.0.1:56385, session= id =3D 0x13aa7c8cf880002, negotiated timeout =3D 5000 [junit4:junit4] 2> 645 T3479 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@15b04b2 name:ZooKeeperConnection= Watcher:127.0.0.1:56385/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 645 T3459 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 663 T3464 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13aa7c8cf880002 type:crea= te cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 672 T3459 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4:junit4] 2> 690 T3459 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:56392_solr [junit4:junit4] 2> 690 T3464 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13aa7c8cf880002 type:dele= te cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:56392_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:56392_solr [junit4:junit4] 2> 708 T3459 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:56392_solr [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 726 T3459 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 726 T3459 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 736 T3459 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4:junit4] 2> 790 T3464 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13aa7c8cf880002 type:dele= te cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/ove= rseer_elect/leader [junit4:junit4] 2> 808 T3459 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4:junit4] 2> 826 T3459 oasc.Overseer.start Overseer (id=3D885676236= 04240386-127.0.0.1:56392_solr-n_0000000000) starting [junit4:junit4] 2> 826 T3464 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13aa7c8cf880002 type:crea= te cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 835 T3464 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13aa7c8cf880002 type:crea= te cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 854 T3464 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13aa7c8cf880002 type:crea= te cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 872 T3464 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13aa7c8cf880002 type:crea= te cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 881 T3481 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 881 T3459 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4:junit4] 2> 917 T3459 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 917 T3480 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 917 T3459 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Sol= r-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.BasicD= istributedZk2Test-controljetty-1351434686744\collection1 [junit4:junit4] 2> 917 T3459 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4:junit4] 2> 917 T3459 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 917 T3459 oasc.ZkController.readConfigName Load collec= tion config from:/collections/control_collection [junit4:junit4] 2> 926 T3459 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-1351434686744\collection1\' [junit4:junit4] 2> 926 T3459 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr= /build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-cont= roljetty-1351434686744/collection1/lib/classes/' to classloader [junit4:junit4] 2> 926 T3459 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr= /build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-cont= roljetty-1351434686744/collection1/lib/README' to classloader [junit4:junit4] 2> 953 T3459 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 999 T3459 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 999 T3459 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 999 T3459 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 1380 T3459 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1389 T3459 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 1389 T3459 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 1407 T3459 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1407 T3459 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1417 T3459 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1417 T3459 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1417 T3459 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-1351434686744\collection1\, dataDir=3D.\org.apache.solr.cloud= .BasicDistributedZk2Test-1351434686335/control/data\ [junit4:junit4] 2> 1417 T3459 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1b5b8a4 [junit4:junit4] 2> 1417 T3459 oasc.SolrCore.initDirectoryFactory solr.Sta= ndardDirectoryFactory [junit4:junit4] 2> 1417 T3459 oasc.CachingDirectoryFactory.injectLockFact= ory WARNING No lockType configured for org.apache.lucene.store.SimpleFSDire= ctory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\= solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-13514346863= 35\control\data lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@1= 3bac3c assuming 'simple' [junit4:junit4] 2> 1417 T3459 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-= 1351434686335\control\data forceNew:false [junit4:junit4] 2> 1426 T3459 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= 1434686335\control\data [junit4:junit4] 2> 1426 T3459 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZk= 2Test-1351434686335/control/data\index/ [junit4:junit4] 2> 1426 T3459 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51434686335\control\data\index' doesn't exist. Creating new index... [junit4:junit4] 2> 1426 T3459 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-= 1351434686335\control\data\index forceNew:false [junit4:junit4] 2> 1462 T3459 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\control\data\index,segFN=3Dsegments_1,= generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 1462 T3459 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 1462 T3459 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= 1434686335\control\data\index [junit4:junit4] 2> 1462 T3459 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 1462 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 1462 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 1462 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 1462 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 1462 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 1462 T3459 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1462 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1462 T3459 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1462 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1462 T3459 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 1462 T3459 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 1462 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1462 T3459 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 1471 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 1471 T3459 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 1480 T3459 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 1480 T3459 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> 1480 T3459 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= 1434686335\control\data [junit4:junit4] 2> 1480 T3459 oass.SolrIndexSearcher. Opening Searc= her@a657e4 main [junit4:junit4] 2> 1480 T3459 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 1480 T3459 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 1480 T3459 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 1516 T3459 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 1680 T3482 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@a657e4 main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 1680 T3459 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 1680 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880002 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> 1725 T3459 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 1725 T3459 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:56392/solr shardId:control_shard [junit4:junit4] 2> 1725 T3459 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leader_elect/control_shard/election [junit4:junit4] 2> 1789 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880002 type:del= ete cxid:0x67 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> 1807 T3459 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 1807 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880002 type:cre= ate cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1807 T3480 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1825 T3480 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:56392_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56392/solr"} [junit4:junit4] 2> 1825 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880002 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> 1952 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (1) [junit4:junit4] 2> 2125 T3459 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 2125 T3459 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 2125 T3459 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:56392/solr/collection1/ [junit4:junit4] 2> 2125 T3459 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 2125 T3459 oasc.SyncStrategy.syncToMe http://127.0.0.1= :56392/solr/collection1/ has no replicas [junit4:junit4] 2> 2125 T3459 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:56392/solr/collection1/ [junit4:junit4] 2> 2125 T3459 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leaders/control_shard [junit4:junit4] 2> 2152 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880002 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> 2279 T3480 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2325 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (1) [junit4:junit4] 2> 2370 T3459 oasc.ZkController.register We are http://12= 7.0.0.1:56392/solr/collection1/ and leader is http://127.0.0.1:56392/solr/c= ollection1/ [junit4:junit4] 2> 2370 T3459 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:56392/solr [junit4:junit4] 2> 2370 T3459 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 2370 T3459 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 2388 T3459 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2388 T3459 oass.SolrDispatchFilter.init user.dir=3DC:\= Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core= \test\J0 [junit4:junit4] 2> 2388 T3459 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 2388 T3459 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 2388 T3459 oascsi.HttpClientUtil.createClient Creating= new http client, config: [junit4:junit4] 2> 2388 T3459 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:56385/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@6db027 [junit4:junit4] 2> 2397 T3483 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:56385 [junit4:junit4] 2> 2397 T3459 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 2397 T3483 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:56385, initiating sessi= on [junit4:junit4] 2> 2397 T3461 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:56400 [junit4:junit4] 2> 2397 T3461 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:56400 [junit4:junit4] 2> 2415 T3463 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x13aa7c8cf880003 with negotiated timeout 10000 for client /1= 27.0.0.1:56400 [junit4:junit4] 2> 2415 T3483 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:56385, sessio= nid =3D 0x13aa7c8cf880003, negotiated timeout =3D 10000 [junit4:junit4] 2> 2415 T3484 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@6db027 name:ZooKeeperConnection= Watcher:127.0.0.1:56385/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 2415 T3459 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 2415 T3459 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 2415 T3459 oasc.ChaosMonkey.monkeyLog monkey: init - e= xpire sessions:true cause connection loss:true [junit4:junit4] 2> 2579 T3459 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 2579 T3459 oejs.AbstractConnector.doStart Started Sele= ctChannelConnector@0.0.0.0:56401 [junit4:junit4] 2> 2579 T3459 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 2588 T3459 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-1351434688741 [junit4:junit4] 2> 2588 T3459 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-1351434688741\' [junit4:junit4] 2> 2606 T3459 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 2606 T3459 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 2606 T3459 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-1351434688741 [junit4:junit4] 2> 2606 T3459 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-1351434688741\solr.xml [junit4:junit4] 2> 2606 T3459 oasc.CoreContainer. New CoreContainer= 22765658 [junit4:junit4] 2> 2606 T3459 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-1351434688741\' [junit4:junit4] 2> 2606 T3459 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-1351434688741\' [junit4:junit4] 2> 2624 T3459 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 2633 T3480 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2633 T3480 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:56392_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56392/solr"} [junit4:junit4] 2> 2633 T3459 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:56385/solr [junit4:junit4] 2> 2633 T3459 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:56385/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@18b8ab0 [junit4:junit4] 2> 2633 T3494 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:56385 [junit4:junit4] 2> 2633 T3459 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 2633 T3494 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:56385, initiating sessi= on [junit4:junit4] 2> 2633 T3461 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:56406 [junit4:junit4] 2> 2642 T3461 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:56406 [junit4:junit4] 2> 2670 T3463 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x13aa7c8cf880004 with negotiated timeout 5000 for client /12= 7.0.0.1:56406 [junit4:junit4] 2> 2670 T3494 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:56385, sessio= nid =3D 0x13aa7c8cf880004, negotiated timeout =3D 5000 [junit4:junit4] 2> 2670 T3495 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@18b8ab0 name:ZooKeeperConnectio= n Watcher:127.0.0.1:56385/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 2670 T3459 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 2670 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880004 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> 2706 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880004 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> 2742 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (1) [junit4:junit4] 2> 2742 T3484 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (1) [junit4:junit4] 2> 2742 T3459 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:56401_solr [junit4:junit4] 2> 2742 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880004 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:56401_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:56401_solr [junit4:junit4] 2> 2760 T3459 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:56401_solr [junit4:junit4] 2> 2788 T3484 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (1) [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 2797 T3479 oascc.ZkStateReader$3.process Updating live= nodes... (2) [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 2797 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 2797 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 2797 T3459 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 2797 T3484 oascc.ZkStateReader$3.process Updating live= nodes... (2) [junit4:junit4] 2> 2815 T3459 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 2824 T3459 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-1351434688741\collection1 [junit4:junit4] 2> 2824 T3459 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 2824 T3459 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 2824 T3459 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 2824 T3459 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-1351434688741\collection1\' [junit4:junit4] 2> 2824 T3459 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-1351434688741/collection1/lib/classes/' to classloader [junit4:junit4] 2> 2824 T3459 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-1351434688741/collection1/lib/README' to classloader [junit4:junit4] 2> 2869 T3459 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 2933 T3459 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 2933 T3459 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 2942 T3459 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 3396 T3459 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 3405 T3459 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 3405 T3459 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 3423 T3459 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 3423 T3459 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 3423 T3459 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 3432 T3459 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 3432 T3459 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-1351434688741\collection1\, dataDir=3D.\org.apache.solr.cloud.Basic= DistributedZk2Test-1351434686335/jetty1\ [junit4:junit4] 2> 3432 T3459 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1b5b8a4 [junit4:junit4] 2> 3432 T3459 oasc.SolrCore.initDirectoryFactory solr.Sta= ndardDirectoryFactory [junit4:junit4] 2> 3432 T3459 oasc.CachingDirectoryFactory.injectLockFact= ory WARNING No lockType configured for org.apache.lucene.store.SimpleFSDire= ctory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\= solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-13514346863= 35\jetty1 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@1f163c4= assuming 'simple' [junit4:junit4] 2> 3432 T3459 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-= 1351434686335\jetty1 forceNew:false [junit4:junit4] 2> 3432 T3459 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= 1434686335\jetty1 [junit4:junit4] 2> 3432 T3459 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZk= 2Test-1351434686335/jetty1\index/ [junit4:junit4] 2> 3432 T3459 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51434686335\jetty1\index' doesn't exist. Creating new index... [junit4:junit4] 2> 3432 T3459 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-= 1351434686335\jetty1\index forceNew:false [junit4:junit4] 2> 3460 T3459 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\jetty1\index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 3460 T3459 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 3460 T3459 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= 1434686335\jetty1\index [junit4:junit4] 2> 3460 T3459 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 3460 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 3460 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 3460 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 3460 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 3460 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 3460 T3459 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 3460 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 3460 T3459 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 3469 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 3478 T3459 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 3478 T3459 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 3478 T3459 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> 3487 T3459 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= 1434686335\jetty1 [junit4:junit4] 2> 3487 T3459 oass.SolrIndexSearcher. Opening Searc= her@eb972d main [junit4:junit4] 2> 3487 T3459 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 3487 T3459 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 3487 T3459 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 3523 T3459 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 3678 T3496 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@eb972d main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 3678 T3459 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 3945 T3480 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3945 T3480 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:56401_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56401/solr"} [junit4:junit4] 2> 3945 T3480 oasc.Overseer$ClusterStateUpdater.createCol= lection Create collection collection1 with numShards 2 [junit4:junit4] 2> 3975 T3495 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 3975 T3484 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 3975 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 3985 T3459 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 3985 T3459 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:56401/solr shardId:shard1 [junit4:junit4] 2> 3985 T3459 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 4054 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880004 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> 4064 T3459 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 4064 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880004 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> 4104 T3459 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 4104 T3459 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 4104 T3459 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:56401/solr/collection1/ [junit4:junit4] 2> 4104 T3459 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 4104 T3459 oasc.SyncStrategy.syncToMe http://127.0.0.1= :56401/solr/collection1/ has no replicas [junit4:junit4] 2> 4104 T3459 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:56401/solr/collection1/ [junit4:junit4] 2> 4104 T3459 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard1 [junit4:junit4] 2> 4134 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880004 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> 4314 T3480 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4384 T3484 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 4384 T3495 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 4384 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 4394 T3459 oasc.ZkController.register We are http://12= 7.0.0.1:56401/solr/collection1/ and leader is http://127.0.0.1:56401/solr/c= ollection1/ [junit4:junit4] 2> 4394 T3459 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:56401/solr [junit4:junit4] 2> 4394 T3459 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 4394 T3459 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 4424 T3459 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4434 T3459 oass.SolrDispatchFilter.init user.dir=3DC:\= Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core= \test\J0 [junit4:junit4] 2> 4434 T3459 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 4434 T3459 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 4624 T3459 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 4624 T3459 oejs.AbstractConnector.doStart Started Sele= ctChannelConnector@0.0.0.0:56407 [junit4:junit4] 2> 4624 T3459 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4624 T3459 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-1351434690760 [junit4:junit4] 2> 4624 T3459 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-1351434690760\' [junit4:junit4] 2> 4644 T3459 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 4644 T3459 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4644 T3459 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-1351434690760 [junit4:junit4] 2> 4644 T3459 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-1351434690760\solr.xml [junit4:junit4] 2> 4644 T3459 oasc.CoreContainer. New CoreContainer= 6567769 [junit4:junit4] 2> 4644 T3459 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-1351434690760\' [junit4:junit4] 2> 4644 T3459 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-1351434690760\' [junit4:junit4] 2> 4664 T3459 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 4684 T3459 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:56385/solr [junit4:junit4] 2> 4684 T3459 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:56385/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@712132 [junit4:junit4] 2> 4684 T3506 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:56385 [junit4:junit4] 2> 4684 T3459 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 4684 T3506 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:56385, initiating sessi= on [junit4:junit4] 2> 4684 T3461 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:56412 [junit4:junit4] 2> 4684 T3461 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:56412 [junit4:junit4] 2> 4694 T3463 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x13aa7c8cf880005 with negotiated timeout 5000 for client /12= 7.0.0.1:56412 [junit4:junit4] 2> 4694 T3506 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:56385, sessio= nid =3D 0x13aa7c8cf880005, negotiated timeout =3D 5000 [junit4:junit4] 2> 4694 T3507 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@712132 name:ZooKeeperConnection= Watcher:127.0.0.1:56385/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 4694 T3459 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 4694 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880005 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> 4704 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880005 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> 4724 T3459 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:56407_solr [junit4:junit4] 2> 4724 T3464 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13aa7c8cf880005 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:56407_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:56407_solr [junit4:junit4] 2> 4734 T3459 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:56407_solr [junit4:junit4] 2> 4744 T3484 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (2) [junit4:junit4] 2> 4744 T3495 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 4744 T3495 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 4744 T3479 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 4744 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 4744 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 4744 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 4754 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 4754 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 4754 T3484 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 4754 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 4754 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 4754 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 4754 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 4754 T3459 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 4754 T3459 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 4754 T3480 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4754 T3480 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:56401_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56401/solr"} [junit4:junit4] 2> 4804 T3459 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 4814 T3484 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 4814 T3507 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> 4814 T3495 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 4814 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 4824 T3459 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-1351434690760\collection1 [junit4:junit4] 2> 4824 T3459 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 4834 T3459 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 4834 T3459 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 4834 T3459 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-1351434690760\collection1\' [junit4:junit4] 2> 4834 T3459 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-1351434690760/collection1/lib/classes/' to classloader [junit4:junit4] 2> 4834 T3459 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-1351434690760/collection1/lib/README' to classloader [junit4:junit4] 2> 4864 T3459 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 4903 T3459 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 4903 T3459 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 4913 T3459 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 5323 T3459 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 5333 T3459 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 5333 T3459 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 5343 T3459 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5343 T3459 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5353 T3459 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5353 T3459 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5353 T3459 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-1351434690760\collection1\, dataDir=3D.\org.apache.solr.cloud.Basic= DistributedZk2Test-1351434686335/jetty2\ [junit4:junit4] 2> 5353 T3459 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1b5b8a4 [junit4:junit4] 2> 5353 T3459 oasc.SolrCore.initDirectoryFactory solr.Sta= ndardDirectoryFactory [junit4:junit4] 2> 5353 T3459 oasc.CachingDirectoryFactory.injectLockFact= ory WARNING No lockType configured for org.apache.lucene.store.SimpleFSDire= ctory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\= solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-13514346863= 35\jetty2 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@90e670 = assuming 'simple' [junit4:junit4] 2> 5353 T3459 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-= 1351434686335\jetty2 forceNew:false [junit4:junit4] 2> 5353 T3459 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= 1434686335\jetty2 [junit4:junit4] 2> 5353 T3459 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZk= 2Test-1351434686335/jetty2\index/ [junit4:junit4] 2> 5353 T3459 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-13= 51434686335\jetty2\index' doesn't exist. Creating new index... [junit4:junit4] 2> 5353 T3459 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-= 1351434686335\jetty2\index forceNew:false [junit4:junit4] 2> 5373 T3459 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\jetty2\index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 5373 T3459 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 5383 T3459 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= 1434686335\jetty2\index [junit4:junit4] 2> 5383 T3459 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 5383 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 5383 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 5383 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 5383 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 5383 T3459 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5383 T3459 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5393 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 5393 T3459 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 5393 T3459 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 5393 T3459 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 5403 T3459 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> 5403 T3459 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= 1434686335\jetty2 [junit4:junit4] 2> 5403 T3459 oass.SolrIndexSearcher. Opening Searc= her@5fa2c7 main [junit4:junit4] 2> 5403 T3459 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 5403 T3459 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 5403 T3459 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 5423 T3459 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 5573 T3508 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@5fa2c7 main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 5573 T3459 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 5792 T3480 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5792 T3480 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:56407_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56407/solr"} [junit4:junit4] 2> 5822 T3495 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 5822 T3484 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 5822 T3479 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... (3) [junit4:junit4] 2> 56200 T3459 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> 56200 T3459 oass.SolrDispatchFilter.init user.dir=3DC:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test\J0 [junit4:junit4] 2> 56200 T3459 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 56200 T3459 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 56536 T3459 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 56536 T3459 oejs.AbstractConnector.doStart Started Sel= ectChannelConnector@0.0.0.0:56413 [junit4:junit4] 2> 56536 T3459 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 56536 T3459 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-1351434742544 [junit4:junit4] 2> 56536 T3459 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-1351434742544\' [junit4:junit4] 2> 56572 T3459 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() [junit4:junit4] 2> 56572 T3459 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 56572 T3459 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-1351434742544 [junit4:junit4] 2> 56572 T3459 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-1351434742544\solr.xml [junit4:junit4] 2> 56589 T3459 oasc.CoreContainer. New CoreContaine= r 29422167 [junit4:junit4] 2> 56589 T3459 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-1351434742544\' [junit4:junit4] 2> 56589 T3459 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-1351434742544\' [junit4:junit4] 2> 56625 T3459 oasc.CoreContainer.load Registering Log Li= stener [junit4:junit4] 2> 56642 T3459 oasc.CoreContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:56385/solr [junit4:junit4] 2> 56642 T3459 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:56385/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@2fe8c2 [junit4:junit4] 2> 56642 T3518 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:56385 [junit4:junit4] 2> 56642 T3459 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 56642 T3518 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to 127.0.0.1/127.0.0.1:56385, initiating sess= ion [junit4:junit4] 2> 56642 T3461 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /127.0.0.1:56418 [junit4:junit4] 2> 56660 T3461 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /127.0.0.1:56418 [junit4:junit4] 2> 56660 T3463 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x13aa7c8cf880006 with negotiated timeout 5000 for client /1= 27.0.0.1:56418 [junit4:junit4] 2> 56660 T3518 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server 127.0.0.1/127.0.0.1:56385, sessi= onid =3D 0x13aa7c8cf880006, negotiated timeout =3D 5000 [junit4:junit4] 2> 56660 T3519 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@2fe8c2 name:ZooKeeperConnectio= n Watcher:127.0.0.1:56385/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 56660 T3459 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 56660 T3464 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa7c8cf880006 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> 56660 T3464 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa7c8cf880006 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> 56678 T3459 oasc.ZkController.createEphemeralLiveNode = Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56413_solr [junit4:junit4] 2> 56678 T3464 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa7c8cf880006 type:de= lete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/live_nodes/127.0.0.1:56413_solr Error:KeeperErrorCode =3D NoNode fo= r /solr/live_nodes/127.0.0.1:56413_solr [junit4:junit4] 2> 56678 T3459 oascc.SolrZkClient.makePath makePath: /liv= e_nodes/127.0.0.1:56413_solr [junit4:junit4] 2> 56678 T3484 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (3) [junit4:junit4] 2> 56678 T3495 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 56678 T3495 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 56678 T3507 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 56678 T3479 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 56678 T3479 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting socketTimeout to: 0 [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting urlScheme to: http:// [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting connTimeout to: 0 [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 56678 T3484 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting corePoolSize to: 0 [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting sizeOfQueue to: -1 [junit4:junit4] 2> 56678 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting fairnessPolicy to: false [junit4:junit4] 2> 56678 T3459 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000= &socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 56696 T3459 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 56696 T3459 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-1351434742544\collection1 [junit4:junit4] 2> 56696 T3459 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:collection1 [junit4:junit4] 2> 56696 T3459 oasc.ZkController.createCollectionZkNode C= ollection zkNode exists [junit4:junit4] 2> 56696 T3459 oasc.ZkController.readConfigName Load coll= ection config from:/collections/collection1 [junit4:junit4] 2> 56713 T3459 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-1351434742544\collection1\' [junit4:junit4] 2> 56713 T3459 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-1351434742544/collection1/lib/classes/' to classloader [junit4:junit4] 2> 56713 T3459 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-1351434742544/collection1/lib/README' to classloader [junit4:junit4] 2> 56766 T3459 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_50 [junit4:junit4] 2> 56837 T3459 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 56837 T3459 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 56855 T3459 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 57580 T3459 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 57598 T3459 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 57598 T3459 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 57616 T3459 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 57633 T3459 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 57633 T3459 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 57633 T3459 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 57633 T3459 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-1351434742544\collection1\, dataDir=3D.\org.apache.solr.cloud.Basi= cDistributedZk2Test-1351434686335/jetty3\ [junit4:junit4] 2> 57633 T3459 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@1b5b8a4 [junit4:junit4] 2> 57633 T3459 oasc.SolrCore.initDirectoryFactory solr.St= andardDirectoryFactory [junit4:junit4] 2> 57633 T3459 oasc.CachingDirectoryFactory.injectLockFac= tory WARNING No lockType configured for org.apache.lucene.store.SimpleFSDir= ectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build= \solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1351434686= 335\jetty3 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@13fd0f= 5 assuming 'simple' [junit4:junit4] 2> 57633 T3459 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= -1351434686335\jetty3 forceNew:false [junit4:junit4] 2> 57633 T3459 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= 51434686335\jetty3 [junit4:junit4] 2> 57633 T3459 oasc.SolrCore.getNewIndexDir New index dir= ectory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZ= k2Test-1351434686335/jetty3\index/ [junit4:junit4] 2> 57633 T3459 oasc.SolrCore.initIndex WARNING [collectio= n1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1= 351434686335\jetty3\index' doesn't exist. Creating new index... [junit4:junit4] 2> 57633 T3459 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= -1351434686335\jetty3\index forceNew:false [junit4:junit4] 2> 57651 T3459 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\jetty3\index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 57651 T3459 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1[segments_1] [junit4:junit4] 2> 57651 T3459 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= 51434686335\jetty3\index [junit4:junit4] 2> 57651 T3459 oasc.SolrCore.initWriters created xml: sol= r.XMLResponseWriter [junit4:junit4] 2> 57651 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 57651 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe-allfields" [junit4:junit4] 2> 57651 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 57651 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= uniq-fields" [junit4:junit4] 2> 57651 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 57651 T3459 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 57651 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 57651 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 57651 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 57651 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created dismax: solr.SearchHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH_Direct: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component= .SearchHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandle= r [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 57669 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 57669 T3459 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 57687 T3459 oasc.SolrCore.initDeprecatedSupport WARNIN= G solrconfig.xml uses deprecated , Please update your = config to use the ShowFileRequestHandler. [junit4:junit4] 2> 57687 T3459 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> 57687 T3459 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= 51434686335\jetty3 [junit4:junit4] 2> 57687 T3459 oass.SolrIndexSearcher. Opening Sear= cher@1abe153 main [junit4:junit4] 2> 57687 T3459 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 57687 T3459 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 57687 T3459 oashc.SpellCheckComponent.inform Initializ= ing spell checkers [junit4:junit4] 2> 57704 T3459 oass.DirectSolrSpellChecker.init init: {na= me=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLe= ngth=3D3} [junit4:junit4] 2> 57775 T3520 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1abe153 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 57775 T3459 oasc.ZkController.publish numShards not fo= und on descriptor - reading it from system property [junit4:junit4] 2> 57793 T3480 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 57793 T3480 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:56413_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56413/solr"} [junit4:junit4] 2> 57793 T3495 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 57793 T3479 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 57793 T3484 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 57793 T3519 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 58341 T3459 oasc.CoreContainer.register registering co= re: collection1 [junit4:junit4] 2> 58341 T3459 oasc.ZkController.register Register shard = - core:collection1 address:http://127.0.0.1:56413/solr shardId:shard1 [junit4:junit4] 2> 58341 T3459 oasc.ZkController.register We are http://1= 27.0.0.1:56413/solr/collection1/ and leader is http://127.0.0.1:56401/solr/= collection1/ [junit4:junit4] 2> 58341 T3459 oasc.ZkController.register No LogReplay ne= eded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:56413/solr [junit4:junit4] 2> 58341 T3459 oasc.ZkController.checkRecovery Core needs= to recover:collection1 [junit4:junit4] 2> 58341 T3459 oasu.DefaultSolrCoreState.doRecovery Runni= ng recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C287 name=3Dcollection1 org.apache.sol= r.core.SolrCore@111d32f url=3Dhttp://127.0.0.1:56413/solr/collection1 node= =3D127.0.0.1:56413_solr [junit4:junit4] 2> 58341 T3521 C287 P56413 oasc.RecoveryStrategy.run Star= ting recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 58341 T3459 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 58341 T3521 C287 P56413 oasc.RecoveryStrategy.doRecove= ry ###### startupVersions=3D[] [junit4:junit4] 2> 58341 T3521 C287 P56413 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [junit4:junit4] 2> 58341 T3459 oass.SolrDispatchFilter.init user.dir=3DC:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test\J0 [junit4:junit4] 2> 58341 T3459 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 58341 T3459 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 58341 T3521 C287 P56413 oasc.RecoveryStrategy.doRecove= ry Attempting to PeerSync from http://127.0.0.1:56401/solr/collection1/ cor= e=3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 58341 T3521 C287 P56413 oasu.PeerSync.sync PeerSync: c= ore=3Dcollection1 url=3Dhttp://127.0.0.1:56413/solr START replicas=3D[http:= //127.0.0.1:56401/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 58341 T3521 C287 P56413 oasu.PeerSync.sync WARNING no = frame of reference to tell of we've missed updates [junit4:junit4] 2> 58341 T3521 C287 P56413 oasc.RecoveryStrategy.doRecove= ry PeerSync Recovery was not successful - trying replication. core=3Dcollec= tion1 [junit4:junit4] 2> 58341 T3521 C287 P56413 oasc.RecoveryStrategy.doRecove= ry Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 58359 T3521 C287 P56413 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 58359 T3480 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 58377 T3480 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:56413_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56413/solr"} [junit4:junit4] 2> ASYNC NEW_CORE C288 name=3Dcollection1 org.apache.sol= r.core.SolrCore@19f6e0a url=3Dhttp://127.0.0.1:56401/solr/collection1 node= =3D127.0.0.1:56401_solr [junit4:junit4] 2> 58377 T3491 C288 P56401 REQ /get {distrib=3Dfalse&qt= =3D/get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 58394 T3495 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 58394 T3479 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 58394 T3519 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 58394 T3484 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 58677 T3459 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 58677 T3459 oejs.AbstractConnector.doStart Started Sel= ectChannelConnector@0.0.0.0:56421 [junit4:junit4] 2> 58695 T3459 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 58695 T3459 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-1351434744685 [junit4:junit4] 2> 58695 T3459 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-1351434744685\' [junit4:junit4] 2> 58731 T3459 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() [junit4:junit4] 2> 58731 T3459 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 58731 T3459 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-1351434744685 [junit4:junit4] 2> 58731 T3459 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-1351434744685\solr.xml [junit4:junit4] 2> 58731 T3459 oasc.CoreContainer. New CoreContaine= r 7421952 [junit4:junit4] 2> 58731 T3459 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-1351434744685\' [junit4:junit4] 2> 58731 T3459 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-1351434744685\' [junit4:junit4] 2> 58748 T3459 oasc.CoreContainer.load Registering Log Li= stener [junit4:junit4] 2> 58784 T3459 oasc.CoreContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:56385/solr [junit4:junit4] 2> 58784 T3459 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:56385/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@f17a5e [junit4:junit4] 2> 58784 T3532 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:56385 [junit4:junit4] 2> 58784 T3459 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 58784 T3532 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to 127.0.0.1/127.0.0.1:56385, initiating sess= ion [junit4:junit4] 2> 58784 T3461 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /127.0.0.1:56426 [junit4:junit4] 2> 58784 T3461 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /127.0.0.1:56426 [junit4:junit4] 2> 58801 T3463 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x13aa7c8cf880007 with negotiated timeout 5000 for client /1= 27.0.0.1:56426 [junit4:junit4] 2> 58801 T3532 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server 127.0.0.1/127.0.0.1:56385, sessi= onid =3D 0x13aa7c8cf880007, negotiated timeout =3D 5000 [junit4:junit4] 2> 58801 T3533 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@f17a5e name:ZooKeeperConnectio= n Watcher:127.0.0.1:56385/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 58801 T3459 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 58801 T3464 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa7c8cf880007 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> 58801 T3464 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa7c8cf880007 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> 58801 T3459 oasc.ZkController.createEphemeralLiveNode = Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56421_solr [junit4:junit4] 2> 58801 T3464 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa7c8cf880007 type:de= lete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/live_nodes/127.0.0.1:56421_solr Error:KeeperErrorCode =3D NoNode fo= r /solr/live_nodes/127.0.0.1:56421_solr [junit4:junit4] 2> 58801 T3459 oascc.SolrZkClient.makePath makePath: /liv= e_nodes/127.0.0.1:56421_solr [junit4:junit4] 2> 58801 T3484 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (4) [junit4:junit4] 2> 58801 T3495 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 58801 T3495 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 58801 T3479 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 58801 T3519 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 58801 T3479 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 58801 T3507 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 58801 T3519 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 58801 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting socketTimeout to: 0 [junit4:junit4] 2> 58801 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting urlScheme to: http:// [junit4:junit4] 2> 58801 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting connTimeout to: 0 [junit4:junit4] 2> 58819 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 58819 T3484 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 58819 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting corePoolSize to: 0 [junit4:junit4] 2> 58819 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 58819 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 58819 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting sizeOfQueue to: -1 [junit4:junit4] 2> 58819 T3459 oashc.HttpShardHandlerFactory.getParameter= Setting fairnessPolicy to: false [junit4:junit4] 2> 58819 T3459 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000= &socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 58837 T3459 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 58837 T3459 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-1351434744685\collection1 [junit4:junit4] 2> 58837 T3459 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:collection1 [junit4:junit4] 2> 58837 T3459 oasc.ZkController.createCollectionZkNode C= ollection zkNode exists [junit4:junit4] 2> 58837 T3459 oasc.ZkController.readConfigName Load coll= ection config from:/collections/collection1 [junit4:junit4] 2> 58837 T3459 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-1351434744685\collection1\' [junit4:junit4] 2> 58837 T3459 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-1351434744685/collection1/lib/classes/' to classloader [junit4:junit4] 2> 58837 T3459 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-1351434744685/collection1/lib/README' to classloader [junit4:junit4] 2> 58890 T3459 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_50 [junit4:junit4] 2> 58978 T3459 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 58978 T3459 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 58978 T3459 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 59757 T3459 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 59775 T3459 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 59775 T3459 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 59792 T3459 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 59792 T3459 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 59810 T3459 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 59810 T3459 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 59810 T3459 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-1351434744685\collection1\, dataDir=3D.\org.apache.solr.cloud.Basi= cDistributedZk2Test-1351434686335/jetty4\ [junit4:junit4] 2> 59810 T3459 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@1b5b8a4 [junit4:junit4] 2> 59810 T3459 oasc.SolrCore.initDirectoryFactory solr.St= andardDirectoryFactory [junit4:junit4] 2> 59810 T3459 oasc.CachingDirectoryFactory.injectLockFac= tory WARNING No lockType configured for org.apache.lucene.store.SimpleFSDir= ectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build= \solr-core\test\J0\org.apache.solr.cloud.BasicDistributedZk2Test-1351434686= 335\jetty4 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@1d1689= 5 assuming 'simple' [junit4:junit4] 2> 59810 T3459 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= -1351434686335\jetty4 forceNew:false [junit4:junit4] 2> 59810 T3459 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= 51434686335\jetty4 [junit4:junit4] 2> 59810 T3459 oasc.SolrCore.getNewIndexDir New index dir= ectory detected: old=3Dnull new=3D.\org.apache.solr.cloud.BasicDistributedZ= k2Test-1351434686335/jetty4\index/ [junit4:junit4] 2> 59810 T3459 oasc.SolrCore.initIndex WARNING [collectio= n1] Solr index directory '.\org.apache.solr.cloud.BasicDistributedZk2Test-1= 351434686335\jetty4\index' doesn't exist. Creating new index... [junit4:junit4] 2> 59810 T3459 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= -1351434686335\jetty4\index forceNew:false [junit4:junit4] 2> 59828 T3459 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\jetty4\index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 59828 T3459 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1[segments_1] [junit4:junit4] 2> 59828 T3459 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= 51434686335\jetty4\index [junit4:junit4] 2> 59828 T3459 oasc.SolrCore.initWriters created xml: sol= r.XMLResponseWriter [junit4:junit4] 2> 59828 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 59828 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe-allfields" [junit4:junit4] 2> 59828 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 59828 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= uniq-fields" [junit4:junit4] 2> 59828 T3459 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created dismax: solr.SearchHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 59828 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH_Direct: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component= .SearchHandler [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandle= r [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 59845 T3459 oasc.RequestHandlers.initHandlersFromConfi= g created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 59845 T3459 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 59863 T3459 oasc.SolrCore.initDeprecatedSupport WARNIN= G solrconfig.xml uses deprecated , Please update your = config to use the ShowFileRequestHandler. [junit4:junit4] 2> 59863 T3459 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> 59863 T3459 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= 51434686335\jetty4 [junit4:junit4] 2> 59863 T3459 oass.SolrIndexSearcher. Opening Sear= cher@f369f6 main [junit4:junit4] 2> 59863 T3459 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 59863 T3459 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 59863 T3459 oashc.SpellCheckComponent.inform Initializ= ing spell checkers [junit4:junit4] 2> 59881 T3459 oass.DirectSolrSpellChecker.init init: {na= me=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLe= ngth=3D3} [junit4:junit4] 2> 59934 T3534 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@f369f6 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 59934 T3459 oasc.ZkController.publish numShards not fo= und on descriptor - reading it from system property [junit4:junit4] 2> 60111 T3480 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 60111 T3480 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:56421_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56421/solr"} [junit4:junit4] 2> 60111 T3484 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60111 T3495 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60111 T3479 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60111 T3519 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60111 T3533 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60500 T3459 oasc.CoreContainer.register registering co= re: collection1 [junit4:junit4] 2> 60500 T3459 oasc.ZkController.register Register shard = - core:collection1 address:http://127.0.0.1:56421/solr shardId:shard2 [junit4:junit4] 2> 60500 T3459 oascc.SolrZkClient.makePath makePath: /col= lections/collection1/leader_elect/shard2/election [junit4:junit4] 2> 60500 T3464 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa7c8cf880007 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> 60500 T3459 oasc.ShardLeaderElectionContext.runLeaderP= rocess Running the leader process. [junit4:junit4] 2> 60500 T3464 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13aa7c8cf880007 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> 60518 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D179982 [junit4:junit4] 2> 60677 T3480 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 60677 T3484 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60677 T3533 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60677 T3519 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60677 T3479 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 60677 T3495 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 61084 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D179416 [junit4:junit4] 2> 61650 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D178850 [junit4:junit4] 2> C287_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:56413_solr, base_url=3Dhttp://1= 27.0.0.1:56413/solr} [junit4:junit4] 2> 61756 T3521 C287 P56413 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 61756 T3521 C287 P56413 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 61756 T3521 C287 P56413 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:56401/solr/collection1/. co= re=3Dcollection1 [junit4:junit4] 2> 61756 T3521 C287 P56413 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 61756 T3492 C288 P56401 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dfal= se,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 61756 T3492 C288 P56401 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-1351434686335\jetty1 [junit4:junit4] 2> 61756 T3492 C288 P56401 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\jetty1\index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 61774 T3492 C288 P56401 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1[segments_1] [junit4:junit4] 2> 61774 T3492 C288 P56401 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-1351434686335\jetty1 [junit4:junit4] 2> 61774 T3492 C288 P56401 oass.SolrIndexSearcher. = Opening Searcher@1ffe0db realtime [junit4:junit4] 2> 61774 T3492 C288 P56401 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 61774 T3492 C288 P56401 /update {waitSearcher=3Dtrue&o= penSearcher=3Dfalse&commit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&soft= Commit=3Dfalse&version=3D2} {commit=3D} 0 18 [junit4:junit4] 2> 61774 T3521 C287 P56413 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 61774 T3521 C287 P56413 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 61774 T3490 C288 P56401 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 61774 T3490 C288 P56401 REQ /replication {command=3Din= dexversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 61774 T3521 C287 P56413 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-1351434686335\jetty3 [junit4:junit4] 2> 61774 T3521 C287 P56413 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\jetty3\index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 61774 T3521 C287 P56413 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1[segments_1] [junit4:junit4] 2> 61774 T3521 C287 P56413 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 61792 T3521 C287 P56413 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\jetty3\index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.B= asicDistributedZk2Test-1351434686335\jetty3\index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 61792 T3521 C287 P56413 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2[segments_2] [junit4:junit4] 2> 61792 T3521 C287 P56413 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-1351434686335\jetty3 [junit4:junit4] 2> 61792 T3521 C287 P56413 oass.SolrIndexSearcher. = Opening Searcher@1c02937 main [junit4:junit4] 2> 61792 T3521 C287 P56413 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 61792 T3520 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1c02937 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 61792 T3520 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= 51434686335\jetty3\index [junit4:junit4] 2> 61792 T3521 C287 P56413 oasc.RecoveryStrategy.replay N= o replay needed. core=3Dcollection1 [junit4:junit4] 2> 61792 T3521 C287 P56413 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 61792 T3521 C287 P56413 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [junit4:junit4] 2> 61792 T3521 C287 P56413 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 61809 T3480 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 61809 T3480 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:56413_solr", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:56413/solr"} [junit4:junit4] 2> 61809 T3484 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 61809 T3479 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 61809 T3519 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 61809 T3495 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 61809 T3533 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... (5) [junit4:junit4] 2> 62216 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D178284 [junit4:junit4] 2> 62783 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D177717 [junit4:junit4] 2> 63349 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D177151 [junit4:junit4] 2> 63915 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D176585 [junit4:junit4] 2> 64481 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D176019 [junit4:junit4] 2> 65097 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D175403 [junit4:junit4] 2> 65720 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D174780 [junit4:junit4] 2> 66343 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D174157 [junit4:junit4] 2> 66966 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D173534 [junit4:junit4] 2> 67589 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D172911 [junit4:junit4] 2> 68211 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D172289 [junit4:junit4] 2> 68834 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D171666 [junit4:junit4] 2> 69457 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D171043 [junit4:junit4] 2> 70080 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D170420 [junit4:junit4] 2> 70703 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D169797 [junit4:junit4] 2> 71326 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D169174 [junit4:junit4] 2> 71949 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D168551 [junit4:junit4] 2> 72572 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D167928 [junit4:junit4] 2> 73194 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D167306 [junit4:junit4] 2> 73817 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D166683 [junit4:junit4] 2> 74440 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D166060 [junit4:junit4] 2> 75063 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D165437 [junit4:junit4] 2> 75686 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D164814 [junit4:junit4] 2> 76309 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D164191 [junit4:junit4] 2> 76932 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D163568 [junit4:junit4] 2> 77609 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D162891 [junit4:junit4] 2> 78294 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D162206 [junit4:junit4] 2> 78979 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D161521 [junit4:junit4] 2> 79664 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D160836 [junit4:junit4] 2> 80350 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D160150 [junit4:junit4] 2> 81035 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D159465 [junit4:junit4] 2> 81720 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D158780 [junit4:junit4] 2> 82405 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D158095 [junit4:junit4] 2> 83090 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D157410 [junit4:junit4] 2> 83775 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D156725 [junit4:junit4] 2> 84460 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D156040 [junit4:junit4] 2> 85146 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D155354 [junit4:junit4] 2> 85831 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D154669 [junit4:junit4] 2> 86516 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D153984 [junit4:junit4] 2> 87201 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D153299 [junit4:junit4] 2> 87886 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D152614 [junit4:junit4] 2> 88571 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D151929 [junit4:junit4] 2> 89256 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D151244 [junit4:junit4] 2> 89942 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D150558 [junit4:junit4] 2> 90627 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D149873 [junit4:junit4] 2> 91369 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D149131 [junit4:junit4] 2> 92119 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D148381 [junit4:junit4] 2> 92869 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D147631 [junit4:junit4] 2> 93619 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D146881 [junit4:junit4] 2> 94369 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D146131 [junit4:junit4] 2> 95119 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D145381 [junit4:junit4] 2> 95869 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D144631 [junit4:junit4] 2> 96619 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D143881 [junit4:junit4] 2> 97369 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D143131 [junit4:junit4] 2> 98119 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D142381 [junit4:junit4] 2> 98869 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D141631 [junit4:junit4] 2> 99619 T3459 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 ti= meoutin=3D140881 [junit4:junit4] 2> 100369 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D140131 [junit4:junit4] 2> 101119 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D139381 [junit4:junit4] 2> 101869 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D138631 [junit4:junit4] 2> 102619 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D137881 [junit4:junit4] 2> 103369 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D137131 [junit4:junit4] 2> 104119 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D136381 [junit4:junit4] 2> 104869 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D135631 [junit4:junit4] 2> 105619 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D134881 [junit4:junit4] 2> 106369 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D134131 [junit4:junit4] 2> 107119 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D133381 [junit4:junit4] 2> 107869 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D132631 [junit4:junit4] 2> 108619 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D131881 [junit4:junit4] 2> 109369 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D131131 [junit4:junit4] 2> 110119 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D130381 [junit4:junit4] 2> 110869 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D129631 [junit4:junit4] 2> 111619 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D128881 [junit4:junit4] 2> 112369 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D128131 [junit4:junit4] 2> 113119 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D127381 [junit4:junit4] 2> 113869 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D126631 [junit4:junit4] 2> 114619 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D125881 [junit4:junit4] 2> 115369 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D125131 [junit4:junit4] 2> 116119 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D124381 [junit4:junit4] 2> 116869 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D123631 [junit4:junit4] 2> 117619 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D122881 [junit4:junit4] 2> 118369 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D122131 [junit4:junit4] 2> 119119 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D121381 [junit4:junit4] 2> 119869 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D120631 [junit4:junit4] 2> 120619 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D119881 [junit4:junit4] 2> 121303 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D119197 [junit4:junit4] 2> 121978 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D118522 [junit4:junit4] 2> 122653 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D117847 [junit4:junit4] 2> 123328 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D117172 [junit4:junit4] 2> 124003 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D116497 [junit4:junit4] 2> 124678 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D115822 [junit4:junit4] 2> 125353 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D115147 [junit4:junit4] 2> 126028 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D114472 [junit4:junit4] 2> 126703 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D113797 [junit4:junit4] 2> 127378 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D113122 [junit4:junit4] 2> 128053 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D112447 [junit4:junit4] 2> 128728 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D111772 [junit4:junit4] 2> 129403 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D111097 [junit4:junit4] 2> 130078 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D110422 [junit4:junit4] 2> 130753 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D109747 [junit4:junit4] 2> 131428 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D109072 [junit4:junit4] 2> 132103 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D108397 [junit4:junit4] 2> 132778 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D107722 [junit4:junit4] 2> 133453 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D107047 [junit4:junit4] 2> 134128 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D106372 [junit4:junit4] 2> 134744 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D105756 [junit4:junit4] 2> 135352 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D105148 [junit4:junit4] 2> 135959 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D104541 [junit4:junit4] 2> 136567 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D103933 [junit4:junit4] 2> 137174 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D103326 [junit4:junit4] 2> 137782 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D102718 [junit4:junit4] 2> 138389 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D102111 [junit4:junit4] 2> 138997 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D101503 [junit4:junit4] 2> 139604 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D100896 [junit4:junit4] 2> 140212 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D100288 [junit4:junit4] 2> 140819 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D99681 [junit4:junit4] 2> 141427 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D99073 [junit4:junit4] 2> 142034 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D98466 [junit4:junit4] 2> 142642 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D97858 [junit4:junit4] 2> 143249 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D97251 [junit4:junit4] 2> 143857 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D96643 [junit4:junit4] 2> 144464 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D96036 [junit4:junit4] 2> 145072 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D95428 [junit4:junit4] 2> 145679 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D94821 [junit4:junit4] 2> 146287 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D94213 [junit4:junit4] 2> 146841 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D93659 [junit4:junit4] 2> 147388 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D93112 [junit4:junit4] 2> 147934 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D92566 [junit4:junit4] 2> 148481 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D92019 [junit4:junit4] 2> 149028 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D91472 [junit4:junit4] 2> 149575 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D90925 [junit4:junit4] 2> 150121 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D90379 [junit4:junit4] 2> 150668 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D89832 [junit4:junit4] 2> 151215 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D89285 [junit4:junit4] 2> 151762 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D88738 [junit4:junit4] 2> 152308 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D88192 [junit4:junit4] 2> 152855 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D87645 [junit4:junit4] 2> 153402 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D87098 [junit4:junit4] 2> 153949 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D86551 [junit4:junit4] 2> 154496 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D86004 [junit4:junit4] 2> 155042 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D85458 [junit4:junit4] 2> 155589 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D84911 [junit4:junit4] 2> 156136 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D84364 [junit4:junit4] 2> 156683 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D83817 [junit4:junit4] 2> 157229 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D83271 [junit4:junit4] 2> 157728 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D82772 [junit4:junit4] 2> 158220 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D82280 [junit4:junit4] 2> 158712 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D81788 [junit4:junit4] 2> 159204 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D81296 [junit4:junit4] 2> 159697 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D80803 [junit4:junit4] 2> 160189 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D80311 [junit4:junit4] 2> 160681 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D79819 [junit4:junit4] 2> 161173 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D79327 [junit4:junit4] 2> 161665 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D78835 [junit4:junit4] 2> 162157 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D78343 [junit4:junit4] 2> 162649 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D77851 [junit4:junit4] 2> 163141 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D77359 [junit4:junit4] 2> 163633 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D76867 [junit4:junit4] 2> 164125 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D76375 [junit4:junit4] 2> 164617 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D75883 [junit4:junit4] 2> 165109 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D75391 [junit4:junit4] 2> 165601 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D74899 [junit4:junit4] 2> 166094 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D74406 [junit4:junit4] 2> 166586 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D73914 [junit4:junit4] 2> 167078 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D73422 [junit4:junit4] 2> 167527 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D72973 [junit4:junit4] 2> 167970 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D72530 [junit4:junit4] 2> 168412 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D72088 [junit4:junit4] 2> 168855 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D71645 [junit4:junit4] 2> 169298 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D71202 [junit4:junit4] 2> 169741 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D70759 [junit4:junit4] 2> 170184 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D70316 [junit4:junit4] 2> 170627 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D69873 [junit4:junit4] 2> 171070 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D69430 [junit4:junit4] 2> 171513 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D68987 [junit4:junit4] 2> 171955 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D68545 [junit4:junit4] 2> 172398 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D68102 [junit4:junit4] 2> 172841 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D67659 [junit4:junit4] 2> 173284 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D67216 [junit4:junit4] 2> 173727 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D66773 [junit4:junit4] 2> 174170 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D66330 [junit4:junit4] 2> 174613 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D65887 [junit4:junit4] 2> 175056 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D65444 [junit4:junit4] 2> 175498 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D65002 [junit4:junit4] 2> 175941 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D64559 [junit4:junit4] 2> 176345 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D64155 [junit4:junit4] 2> 176744 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D63756 [junit4:junit4] 2> 177143 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D63357 [junit4:junit4] 2> 177541 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D62959 [junit4:junit4] 2> 177940 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D62560 [junit4:junit4] 2> 178338 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D62162 [junit4:junit4] 2> 178737 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D61763 [junit4:junit4] 2> 179136 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D61364 [junit4:junit4] 2> 179534 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D60966 [junit4:junit4] 2> 179933 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D60567 [junit4:junit4] 2> 180331 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D60169 [junit4:junit4] 2> 180730 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D59770 [junit4:junit4] 2> 181129 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D59371 [junit4:junit4] 2> 181527 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D58973 [junit4:junit4] 2> 181926 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D58574 [junit4:junit4] 2> 182324 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D58176 [junit4:junit4] 2> 182723 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D57777 [junit4:junit4] 2> 183121 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D57379 [junit4:junit4] 2> 183520 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D56980 [junit4:junit4] 2> 183919 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D56581 [junit4:junit4] 2> 184282 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D56218 [junit4:junit4] 2> 184641 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D55859 [junit4:junit4] 2> 185000 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D55500 [junit4:junit4] 2> 185359 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D55141 [junit4:junit4] 2> 185717 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D54783 [junit4:junit4] 2> 186076 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D54424 [junit4:junit4] 2> 186435 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D54065 [junit4:junit4] 2> 186794 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D53706 [junit4:junit4] 2> 187152 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D53348 [junit4:junit4] 2> 187511 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D52989 [junit4:junit4] 2> 187870 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D52630 [junit4:junit4] 2> 188228 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D52272 [junit4:junit4] 2> 188587 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D51913 [junit4:junit4] 2> 188946 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D51554 [junit4:junit4] 2> 189305 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D51195 [junit4:junit4] 2> 189663 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D50837 [junit4:junit4] 2> 190022 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D50478 [junit4:junit4] 2> 190381 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D50119 [junit4:junit4] 2> 190740 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D49760 [junit4:junit4] 2> 191098 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D49402 [junit4:junit4] 2> 191426 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D49074 [junit4:junit4] 2> 191749 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D48751 [junit4:junit4] 2> 192071 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D48429 [junit4:junit4] 2> 192394 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D48106 [junit4:junit4] 2> 192717 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D47783 [junit4:junit4] 2> 193040 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D47460 [junit4:junit4] 2> 193363 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D47137 [junit4:junit4] 2> 193686 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D46814 [junit4:junit4] 2> 194009 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D46491 [junit4:junit4] 2> 194331 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D46169 [junit4:junit4] 2> 194654 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D45846 [junit4:junit4] 2> 194977 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D45523 [junit4:junit4] 2> 195300 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D45200 [junit4:junit4] 2> 195623 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D44877 [junit4:junit4] 2> 195946 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D44554 [junit4:junit4] 2> 196269 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D44231 [junit4:junit4] 2> 196591 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D43909 [junit4:junit4] 2> 196914 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 t= imeoutin=3D43586 [junit4:junit4] 2> 197237 T3459 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: tot [...truncated too long message...] y, TestBadConfig, PreAnalyzedFieldTest, LoggingHandlerTest, SoftAutoCommitT= est, TestRangeQuery, UpdateRequestProcessorFactoryTest, IndexReaderFactoryT= est, TestSort, TestSolrCoreProperties, TestCSVLoader, DistributedQueryEleva= tionComponentTest, TestLMDirichletSimilarityFactory, TestFoldingMultitermQu= ery, DOMUtilTest, BadComponentTest, TestReplicationHandler, TestQueryTypes,= TestExtendedDismaxParser, LeaderElectionTest, TestSolrIndexConfig, TestQue= rySenderListener, ScriptEngineTest, JSONWriterTest, PathHierarchyTokenizerF= actoryTest, TestJmxMonitoredMap, TestValueSourceCache, TestUpdate, Suggeste= rWFSTTest, MoreLikeThisHandlerTest, SyncSliceTest, FileUtilsTest, RecoveryZ= kTest, SpellCheckCollatorTest, PluginInfoTest, PingRequestHandlerTest, Over= seerTest, TestHashPartitioner, TestAnalyzedSuggestions, DirectUpdateHandler= Test, SpatialFilterTest, TestDefaultSimilarityFactory, BasicDistributedZkTe= st, SpellCheckComponentTest, FullSolrCloudDistribCmdsTest, LeaderElectionIn= tegrationTest, DistributedSpellCheckComponentTest, TestRealTimeGet, TestDis= tributedGrouping, TestRandomFaceting, WordBreakSolrSpellCheckerTest, Distri= butedTermsComponentTest, TestRecovery, TestJoin, IndexBasedSpellCheckerTest= , TestMultiCoreConfBootstrap, TestCoreContainer, LukeRequestHandlerTest, Di= rectSolrSpellCheckerTest, TestFiltering, FileBasedSpellCheckerTest, Convert= edLegacyTest, PeerSyncTest, QueryElevationComponentTest, SortByFunctionTest= , BasicFunctionalityTest, SignatureUpdateProcessorFactoryTest, TestJmxInteg= ration, SampleTest, HighlighterTest, TestIndexSearcher, TestRemoteStreaming= , QueryEqualityTest, TestCSVResponseWriter, DocumentBuilderTest, MBeansHand= lerTest, CurrencyFieldTest, TestPseudoReturnFields, DisMaxRequestHandlerTes= t, IndexSchemaTest, BinaryUpdateRequestHandlerTest, TermsComponentTest, Xsl= tUpdateRequestHandlerTest, TermVectorComponentTest, JsonLoaderTest, TestSur= roundQueryParser, DocumentAnalysisRequestHandlerTest, DirectUpdateHandlerOp= timizeTest, TestSolrQueryParser, SolrPluginUtilsTest, TestReversedWildcardF= ilterFactory, DebugComponentTest, ReturnFieldsTest, SearchHandlerTest, Test= OmitPositions, SuggesterFSTTest, CopyFieldTest, SolrCmdDistributorTest, Uni= qFieldsUpdateProcessorFactoryTest, SuggesterTest, CSVRequestHandlerTest, Sh= owFileRequestHandlerTest, TestSearchPerf, NotRequiredUniqueKeyTest, TestSol= rDeletionPolicy1, TestLuceneMatchVersion, TestWriterPerf, TestIndexingPerfo= rmance, PrimitiveFieldTypeTest, FieldMutatingUpdateProcessorTest, TestMerge= PolicyConfig, TestDocSet, TestLFUCache, TestCollationField, NumericFieldsTe= st, OutputWriterTest, MultiTermTest, TestConfig, EchoParamsTest, TestBM25Si= milarityFactory, TestPerFieldSimilarity, TestPluginEnable, TestLMJelinekMer= cerSimilarityFactory, TestDFRSimilarityFactory, TestCodecSupport, TimeZoneU= tilsTest, DateMathParserTest, URLClassifyProcessorTest, ResourceLoaderTest,= TestSolrXMLSerializer, DateFieldTest, SpellingQueryConverterTest, TestSugg= estSpellingConverter, SystemInfoHandlerTest, TestUtils, ZkNodePropsTest, An= alysisAfterCoreReloadTest, TestDocumentBuilder, TestCharFilters, BasicDistr= ibutedZk2Test] [junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DBasicDist= ributedZk2Test -Dtests.seed=3DAD1EE9BD55A316B6 -Dtests.slow=3Dtrue -Dtests.= locale=3Dsr_BA_#Latn -Dtests.timezone=3DCanada/Mountain -Dtests.file.encodi= ng=3DCp1252 [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([AD1EE9BD55A31= 6B6]: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.GeneratedMethodAccessor21.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=3D3508, name=3DsearcherExecutor-2237-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([AD1EE9BD55A31= 6B6]: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=3D3508, name=3DsearcherExecutor-2237-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([AD1EE9BD55A31= 6B6]:0) [junit4:junit4] Completed in 1037.38s, 1 test, 2 failures, 2 errors <<< FAI= LURES! [...truncated 49 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, 45 ignored (6 assumptions) Total time: 52 minutes 33 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Description set: Java: 32bit/jdk1.7.0_07 -client -XX:+UseSerialGC Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_133_107448049.1351435728292 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_133_107448049.1351435728292--