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 6007F18DDF for ; Wed, 13 Jan 2016 18:30:17 +0000 (UTC) Received: (qmail 72906 invoked by uid 500); 13 Jan 2016 18:30:16 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 72851 invoked by uid 500); 13 Jan 2016 18:30:16 -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 72816 invoked by uid 99); 13 Jan 2016 18:30:16 -0000 Received: from crius.apache.org (HELO crius.apache.org) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 13 Jan 2016 18:30:16 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius.apache.org (ASF Mail Server at crius.apache.org) with ESMTP id 910699C01A9 for ; Wed, 13 Jan 2016 18:30:11 +0000 (UTC) Date: Wed, 13 Jan 2016 18:29:29 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <1174394210.726.1452709811442.JavaMail.jenkins@crius> In-Reply-To: <768895822.320.1452621391969.JavaMail.jenkins@crius> References: <768895822.320.1452621391969.JavaMail.jenkins@crius> Subject: [JENKINS] Lucene-Solr-NightlyTests-5.4 - Build # 16 - Still Failing MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_725_1438596694.1452709811000" X-Jenkins-Job: Lucene-Solr-NightlyTests-5.4 X-Jenkins-Result: FAILURE ------=_Part_725_1438596694.1452709811000 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.4/16/ 3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Error from server at http://127.0.0.1:35181/awholynewcollection_2: non ok s= tatus: 500, message:Server Error Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error= from server at http://127.0.0.1:35181/awholynewcollection_2: non ok status= : 500, message:Server Error =09at __randomizedtesting.SeedInfo.seed([D38CC17F34E6FDAA:5BD8FEA59A1A9052]= :0) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:508) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:241) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:230) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:150= ) =09at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943) =09at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForNon403or40= 4or503(AbstractFullDistribZkTestBase.java:1754) =09at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollections= API(CollectionsAPIDistributedZkTest.java:658) =09at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(Collection= sAPIDistributedZkTest.java:160) =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:606) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1764) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:871) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:921) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:964) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:939) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:65) =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:367) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:809) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:460) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:880) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:781) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:816) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:827) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:54) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:65) =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:367) =09at java.lang.Thread.run(Thread.java:745) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDist= ributedZkTest Error Message: 6 threads leaked from SUITE scope at org.apache.solr.cloud.CollectionsAPIDi= stributedZkTest: 1) Thread[id=3D53620, name=3DzkCallback-675-thread-3, = state=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] = at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.lo= cks.LockSupport.parkNanos(LockSupport.java:226) at java.util.concur= rent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)= at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Sy= nchronousQueue.java:359) at java.util.concurrent.SynchronousQueue.p= oll(SynchronousQueue.java:942) at java.util.concurrent.ThreadPoolEx= ecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurren= t.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at jav= a.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615= ) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=3D53621,= name=3DzkCallback-675-thread-4, state=3DTIMED_WAITING, group=3DTGRP-Collec= tionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) = at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java= :226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitF= ulfill(SynchronousQueue.java:460) at java.util.concurrent.Synchrono= usQueue$TransferStack.transfer(SynchronousQueue.java:359) at java.u= til.concurrent.SynchronousQueue.poll(SynchronousQueue.java:942) at = java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:106= 8) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolE= xecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worke= r.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.j= ava:745) 3) Thread[id=3D52355, name=3DzkCallback-675-thread-1, state=3DT= IMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.= misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockS= upport.parkNanos(LockSupport.java:226) at java.util.concurrent.Sync= hronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) = at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Synchronous= Queue.java:359) at java.util.concurrent.SynchronousQueue.poll(Synch= ronousQueue.java:942) at java.util.concurrent.ThreadPoolExecutor.ge= tTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadP= oolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.co= ncurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) = at java.lang.Thread.run(Thread.java:745) 4) Thread[id=3D52353, name=3DT= EST-CollectionsAPIDistributedZkTest.test-seed#[D38CC17F34E6FDAA]-SendThread= (127.0.0.1:33845), state=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistri= butedZkTest] at java.lang.Thread.sleep(Native Method) at or= g.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:1= 01) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(Clie= ntCnxn.java:940) at org.apache.zookeeper.ClientCnxn$SendThread.run(= ClientCnxn.java:1003) 5) Thread[id=3D52354, name=3DTEST-CollectionsAPIDi= stributedZkTest.test-seed#[D38CC17F34E6FDAA]-EventThread, state=3DWAITING, = group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.pa= rk(Native Method) at java.util.concurrent.locks.LockSupport.park(Lo= ckSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSyn= chronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) = at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.jav= a:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnx= n.java:494) 6) Thread[id=3D53619, name=3DzkCallback-675-thread-2, state= =3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] at = sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.L= ockSupport.parkNanos(LockSupport.java:226) at java.util.concurrent.= SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) = at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Synchro= nousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(S= ynchronousQueue.java:942) at java.util.concurrent.ThreadPoolExecuto= r.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.Thr= eadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.uti= l.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) = at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 6 threads leaked from S= UITE scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest:=20 1) Thread[id=3D53620, name=3DzkCallback-675-thread-3, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=3D53621, name=3DzkCallback-675-thread-4, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:745) 3) Thread[id=3D52355, name=3DzkCallback-675-thread-1, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:745) 4) Thread[id=3D52353, name=3DTEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]-SendThread(127.0.0.1:33845), state=3DTIMED_WAITING, = group=3DTGRP-CollectionsAPIDistributedZkTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostPr= ovider.java:101) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCn= xn.java:940) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1= 003) 5) Thread[id=3D52354, name=3DTEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]-EventThread, state=3DWAITING, group=3DTGRP-Collectio= nsAPIDistributedZkTest] 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 org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:= 494) 6) Thread[id=3D53619, name=3DzkCallback-675-thread-2, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:745) =09at __randomizedtesting.SeedInfo.seed([D38CC17F34E6FDAA]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDist= ributedZkTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id= =3D52353, name=3DTEST-CollectionsAPIDistributedZkTest.test-seed#[D38CC17F34= E6FDAA]-SendThread(127.0.0.1:33845), state=3DTIMED_WAITING, group=3DTGRP-Co= llectionsAPIDistributedZkTest] at java.lang.Thread.sleep(Native Met= hod) at org.apache.zookeeper.client.StaticHostProvider.next(StaticH= ostProvider.java:101) at org.apache.zookeeper.ClientCnxn$SendThread= .startConnect(ClientCnxn.java:940) at org.apache.zookeeper.ClientCn= xn$SendThread.run(ClientCnxn.java:1003) 2) Thread[id=3D52355, name=3DzkC= allback-675-thread-1, state=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDis= tributedZkTest] at sun.misc.Unsafe.park(Native Method) at j= ava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226) = at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(Sync= hronousQueue.java:460) at java.util.concurrent.SynchronousQueue$Tra= nsferStack.transfer(SynchronousQueue.java:359) at java.util.concurr= ent.SynchronousQueue.poll(SynchronousQueue.java:942) at java.util.c= oncurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) = at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.jav= a:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Threa= dPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) = 3) Thread[id=3D53619, name=3DzkCallback-675-thread-2, state=3DTIMED_WAITIN= G, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe= .park(Native Method) at java.util.concurrent.locks.LockSupport.park= Nanos(LockSupport.java:226) at java.util.concurrent.SynchronousQueu= e$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.uti= l.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:= 359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue= .java:942) at java.util.concurrent.ThreadPoolExecutor.getTask(Threa= dPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor= .runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.Th= readPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.la= ng.Thread.run(Thread.java:745) 4) Thread[id=3D53620, name=3DzkCallback-6= 75-thread-3, state=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZ= kTest] at sun.misc.Unsafe.park(Native Method) at java.util.= concurrent.locks.LockSupport.parkNanos(LockSupport.java:226) at jav= a.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQu= eue.java:460) at java.util.concurrent.SynchronousQueue$TransferStac= k.transfer(SynchronousQueue.java:359) at java.util.concurrent.Synch= ronousQueue.poll(SynchronousQueue.java:942) at java.util.concurrent= .ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.u= til.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) = at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExec= utor.java:615) at java.lang.Thread.run(Thread.java:745) 5) Threa= d[id=3D53621, name=3DzkCallback-675-thread-4, state=3DTIMED_WAITING, group= =3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Na= tive Method) at java.util.concurrent.locks.LockSupport.parkNanos(Lo= ckSupport.java:226) at java.util.concurrent.SynchronousQueue$Transf= erStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concur= rent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:359) = at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:94= 2) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExe= cutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPool= Executor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Threa= d.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie = threads that couldn't be terminated: 1) Thread[id=3D52353, name=3DTEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]-SendThread(127.0.0.1:33845), state=3DTIMED_WAITING, = group=3DTGRP-CollectionsAPIDistributedZkTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostPr= ovider.java:101) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCn= xn.java:940) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1= 003) 2) Thread[id=3D52355, name=3DzkCallback-675-thread-1, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:745) 3) Thread[id=3D53619, name=3DzkCallback-675-thread-2, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:745) 4) Thread[id=3D53620, name=3DzkCallback-675-thread-3, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:745) 5) Thread[id=3D53621, name=3DzkCallback-675-thread-4, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:615) at java.lang.Thread.run(Thread.java:745) =09at __randomizedtesting.SeedInfo.seed([D38CC17F34E6FDAA]:0) Build Log: [...truncated 10929 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Luce= ne-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/temp/solr.cloud.Colle= ctionsAPIDistributedZkTest_D38CC17F34E6FDAA-001/init-core-data-001 [junit4] 2> 1752331 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#= [D38CC17F34E6FDAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (fals= e) and clientAuth (false) [junit4] 2> 1752331 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#= [D38CC17F34E6FDAA]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setti= ng hostContext system property: / [junit4] 2> 1752336 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1752341 INFO (Thread-48508) [ ] o.a.s.c.ZkTestServer c= lient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1752341 INFO (Thread-48508) [ ] o.a.s.c.ZkTestServer S= tarting server [junit4] 2> 1752443 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.ZkTestServer start zk server on port= :33845 [junit4] 2> 1752443 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCrede= ntialsProvider [junit4] 2> 1752460 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Waiting for clie= nt to connect to ZooKeeper [junit4] 2> 1752489 INFO (zkCallback-642-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@616= 2d55f name:ZooKeeperConnection Watcher:127.0.0.1:33845 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1752489 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Client is connec= ted to ZooKeeper [junit4] 2> 1752489 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPr= ovider [junit4] 2> 1752489 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1752532 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCrede= ntialsProvider [junit4] 2> 1752532 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Waiting for clie= nt to connect to ZooKeeper [junit4] 2> 1752534 INFO (zkCallback-643-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6bd= 7a2cc name:ZooKeeperConnection Watcher:127.0.0.1:33845/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1752534 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Client is connec= ted to ZooKeeper [junit4] 2> 1752534 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPr= ovider [junit4] 2> 1752535 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collection= s/collection1 [junit4] 2> 1752536 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collection= s/collection1/shards [junit4] 2> 1752537 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collection= s/control_collection [junit4] 2> 1752538 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collection= s/control_collection/shards [junit4] 2> 1752540 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xm= l [junit4] 2> 1752540 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/solrconfig.xml [junit4] 2> 1752542 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1752542 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/schema.xml [junit4] 2> 1752544 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /config= s/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1752544 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1752545 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1752546 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/stopwords.txt [junit4] 2> 1752547 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1752547 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/protwords.txt [junit4] 2> 1752548 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1752548 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/currency.xml [junit4] 2> 1752549 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1752550 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/enumsConfig.xml [junit4] 2> 1752551 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exc= hange-rates.json [junit4] 2> 1752551 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/open-exchange-rates.json [junit4] 2> 1752564 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mappi= ng-ISOLatin1Accent.txt [junit4] 2> 1752564 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1752566 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1752566 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/old_synonyms.txt [junit4] 2> 1752567 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1752567 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf1/synonyms.txt [junit4] 2> 1752579 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCrede= ntialsProvider [junit4] 2> 1752585 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Waiting for clie= nt to connect to ZooKeeper [junit4] 2> 1752608 INFO (zkCallback-644-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e6c= b061 name:ZooKeeperConnection Watcher:127.0.0.1:33845 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1752608 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Client is connec= ted to ZooKeeper [junit4] 2> 1752608 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPr= ovider [junit4] 2> 1752608 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1752611 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCrede= ntialsProvider [junit4] 2> 1752613 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Waiting for clie= nt to connect to ZooKeeper [junit4] 2> 1752616 INFO (zkCallback-645-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@244= 25aa1 name:ZooKeeperConnection Watcher:127.0.0.1:33845/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1752616 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Client is connec= ted to ZooKeeper [junit4] 2> 1752616 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPr= ovider [junit4] 2> 1752616 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xm= l [junit4] 2> 1752617 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/solrconfig.xml [junit4] 2> 1752618 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 1752618 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/schema.xml [junit4] 2> 1752620 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 1752620 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/enumsConfig.xml [junit4] 2> 1752621 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /config= s/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1752622 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1752623 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 1752623 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/stopwords.txt [junit4] 2> 1752624 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 1752624 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/protwords.txt [junit4] 2> 1752626 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 1752626 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/currency.xml [junit4] 2> 1752627 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exc= hange-rates.json [junit4] 2> 1752627 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/open-exchange-rates.json [junit4] 2> 1752629 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mappi= ng-ISOLatin1Accent.txt [junit4] 2> 1752629 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1752631 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 1752631 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/old_synonyms.txt [junit4] 2> 1752638 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 1752639 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/synonyms.txt [junit4] 2> 1752641 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/core/src/test-file= s/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 1752641 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/co= nf2/elevate.xml [junit4] 2> 1752774 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties= file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/s= olr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest= _D38CC17F34E6FDAA-001/control-001/cores/collection1 [junit4] 2> 1752777 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1752785 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Ser= vletContextHandler@2a5567db{/,null,AVAILABLE} [junit4] 2> 1752787 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.e.j.s.ServerConnector Started ServerConnec= tor@4d157093{HTTP/1.1}{127.0.0.1:46789} [junit4] 2> 1752787 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.e.j.s.Server Started @1755837ms [junit4] 2> 1752787 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties= : {coreRootDirectory=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Nigh= tlyTests-5.4/solr/build/solr-core/test/J1/../../../../../../../../../../x1/= jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/build/sol= r-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6= FDAA-001/control-001/cores, hostPort=3D46789, hostContext=3D/, solr.data.di= r=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/b= uild/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D38C= C17F34E6FDAA-001/tempDir-001/control/data} [junit4] 2> 1752787 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilte= r.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 1752787 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceL= oader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Night= lyTests-5.4/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDist= ributedZkTest_D38CC17F34E6FDAA-001/control-001' [junit4] 2> 1752787 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.SolrResourceLoader JNDI not configur= ed for solr (NoInitialContextEx) [junit4] 2> 1752787 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.SolrResourceLoader solr home default= ed to 'solr/' (could not find system property or JNDI) [junit4] 2> 1752788 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCrede= ntialsProvider [junit4] 2> 1752788 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Waiting for clie= nt to connect to ZooKeeper [junit4] 2> 1752796 INFO (zkCallback-646-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@677= d8e12 name:ZooKeeperConnection Watcher:127.0.0.1:33845/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1752796 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Client is connec= ted to ZooKeeper [junit4] 2> 1752797 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPr= ovider [junit4] 2> 1752799 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml = from SolrHome (not found in ZooKeeper) [junit4] 2> 1752799 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.SolrXmlConfig Loading container conf= iguration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests= -5.4/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributed= ZkTest_D38CC17F34E6FDAA-001/control-001/solr.xml [junit4] 2> 1752814 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.CorePropertiesLocator Config-defined= core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Night= lyTests-5.4/solr/build/solr-core/test/J1/../../../../../../../../../../x1/j= enkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/build/solr= -core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6F= DAA-001/control-001/cores [junit4] 2> 1752814 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.CoreContainer New CoreContainer 1395= 666871 [junit4] 2> 1752814 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.CoreContainer Loading cores into Cor= eContainer [instanceDir=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-N= ightlyTests-5.4/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPI= DistributedZkTest_D38CC17F34E6FDAA-001/control-001] [junit4] 2> 1752814 WARN (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.CoreContainer Couldn't add files fro= m /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/bui= ld/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D38CC1= 7F34E6FDAA-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/work= space/Lucene-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/temp/solr.c= loud.CollectionsAPIDistributedZkTest_D38CC17F34E6FDAA-001/control-001/lib [junit4] 2> 1752814 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.h.c.HttpShardHandlerFactory created wi= th socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPer= Host : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483= 647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetrie= s : false, [junit4] 2> 1752815 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateSh= ardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D45= 000&retry=3Dtrue [junit4] 2> 1752816 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.i= mpl.Log4jLoggerFactory [junit4] 2> 1752816 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.l.LogWatcher Registering Log Listener = [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1752816 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0= .0.1:33845/solr [junit4] 2> 1752816 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1752816 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCrede= ntialsProvider [junit4] 2> 1752823 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Waiting for clie= nt to connect to ZooKeeper [junit4] 2> 1752823 INFO (zkCallback-648-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@333= 924d4 name:ZooKeeperConnection Watcher:127.0.0.1:33845 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1752823 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.ConnectionManager Client is connec= ted to ZooKeeper [junit4] 2> 1752824 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPr= ovider [junit4] 2> 1752827 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.ConnectionManage= r Waiting for client to connect to ZooKeeper [junit4] 2> 1752836 INFO (zkCallback-649-thread-1-processing-n:127.0.= 0.1:46789_) [n:127.0.0.1:46789_ ] o.a.s.c.c.ConnectionManager Watcher or= g.apache.solr.common.cloud.ConnectionManager@76293883 name:ZooKeeperConnect= ion Watcher:127.0.0.1:33845/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4] 2> 1752836 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.ConnectionManage= r Client is connected to ZooKeeper [junit4] 2> 1752837 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer/queue [junit4] 2> 1752839 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer/collection-queue-work [junit4] 2> 1752841 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer/collection-map-running [junit4] 2> 1752842 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer/collection-map-completed [junit4] 2> 1752844 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer/collection-map-failure [junit4] 2> 1752845 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /live_nodes [junit4] 2> 1752846 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /aliases.json [junit4] 2> 1752847 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /clusterstate.json [junit4] 2> 1752848 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /security.json [junit4] 2> 1752849 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.ZkController Regis= ter node as live in ZooKeeper:/live_nodes/127.0.0.1:46789_ [junit4] 2> 1752850 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /live_nodes/127.0.0.1:46789_ [junit4] 2> 1752851 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer_elect [junit4] 2> 1752852 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer_elect/election [junit4] 2> 1752853 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.Overseer Overseer = (id=3Dnull) closing [junit4] 2> 1752853 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.LeaderElector Join= ed leadership election with path: /overseer_elect/election/9520474854785024= 6-127.0.0.1:46789_-n_0000000000 [junit4] 2> 1752854 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.OverseerElectionCo= ntext I am going to be the leader 127.0.0.1:46789_ [junit4] 2> 1752854 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer_elect/leader [junit4] 2> 1752855 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.Overseer Overseer = (id=3D95204748547850246-127.0.0.1:46789_-n_0000000000) starting [junit4] 2> 1752856 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.SolrZkClient mak= ePath: /overseer/queue-work [junit4] 2> 1752861 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.OverseerAutoReplic= aFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailo= verWorkLoopDelay=3D10000 autoReplicaFailoverWaitAfterExpiration=3D10000 aut= oReplicaFailoverBadNodeExpiration=3D60000 [junit4] 2> 1752873 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.Overseer Startin= g to work on the main queue [junit4] 2> 1752873 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.c.ZkStateReader = Updating cluster state from ZooKeeper...=20 [junit4] 2> 1752880 INFO (OverseerCollectionConfigSetProcessor-952047= 48547850246-127.0.0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c= .OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1752881 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.c.ZkStateReader Up= dating cluster state from ZooKeeper...=20 [junit4] 2> 1752912 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.CoreContainer Secu= rity conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1752912 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.CoreContainer No a= uthentication plugin used. [junit4] 2> 1752913 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.CorePropertiesLoca= tor Looking for core definitions underneath /x1/jenkins/jenkins-slave/works= pace/Lucene-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/../../../../= ../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTes= ts-5.4/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistribut= edZkTest_D38CC17F34E6FDAA-001/control-001/cores [junit4] 2> 1752914 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.CoreDescriptor Cre= ated CoreDescriptor: {transient=3Dfalse, name=3Dcollection1, absoluteInstDi= r=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/b= uild/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-sla= ve/workspace/Lucene-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/temp= /solr.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6FDAA-001/control-00= 1/cores/collection1/, instanceDir=3D/x1/jenkins/jenkins-slave/workspace/Luc= ene-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/../../../../../../..= /../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/s= olr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest= _D38CC17F34E6FDAA-001/control-001/cores/collection1, schema=3Dschema.xml, c= onfigSetProperties=3Dconfigsetprops.json, collection=3Dcontrol_collection, = dataDir=3Ddata/, coreNodeName=3D, config=3Dsolrconfig.xml, loadOnStartup=3D= true, shard=3D} [junit4] 2> 1752914 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.CorePropertiesLoca= tor Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-So= lr-NightlyTests-5.4/solr/build/solr-core/test/J1/../../../../../../../../..= /../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/bu= ild/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D38CC= 17F34E6FDAA-001/control-001/cores/collection1/ [junit4] 2> 1752915 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.c.CorePropertiesLoca= tor Found 1 core definitions [junit4] 2> 1752916 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.s.SolrDispatchFilter= user.dir=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.= 4/solr/build/solr-core/test/J1 [junit4] 2> 1752916 INFO (TEST-CollectionsAPIDistributedZkTest.test-s= eed#[D38CC17F34E6FDAA]) [n:127.0.0.1:46789_ ] o.a.s.s.SolrDispatchFilter= SolrDispatchFilter.init() done [junit4] 2> 1752916 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.ZkController publishing state=3Ddown [junit4] 2> 1752916 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.ZkController numShards not found on descriptor - reading it from = system property [junit4] 2> 1752918 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "base_url":"http://127.0.0.1:46789", [junit4] 2> "node_name":"127.0.0.1:46789_", [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "shard":null, [junit4] 2> "state":"down", [junit4] 2> "roles":null} current state version: 0 [junit4] 2> 1752919 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.o.ReplicaMutator= Update state numShards=3D1 message=3D{ [junit4] 2> "base_url":"http://127.0.0.1:46789", [junit4] 2> "node_name":"127.0.0.1:46789_", [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "shard":null, [junit4] 2> "state":"down", [junit4] 2> "roles":null} [junit4] 2> 1752919 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.ZkController look for our core node name [junit4] 2> 1752919 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.o.ClusterStateMu= tator building a new cName: control_collection [junit4] 2> 1752920 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.o.ReplicaMutator= Assigning new node to shard shard=3Dshard1 [junit4] 2> 1752921 INFO (zkCallback-649-thread-1-processing-n:127.0.= 0.1:46789_) [n:127.0.0.1:46789_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1752921 INFO (zkCallback-649-thread-2-processing-n:127.0.= 0.1:46789_) [n:127.0.0.1:46789_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1753920 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.ZkController waiting to find shard id in clusterstate for collect= ion1 [junit4] 2> 1753920 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1753920 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1753920 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_= collection [junit4] 2> 1753921 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.c.ZkStateReader path=3D/collections/control_collection configName= =3Dconf1 specified config exists in ZooKeeper [junit4] 2> 1753921 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jen= kins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/build/solr-c= ore/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6FDA= A-001/control-001/cores/collection1' [junit4] 2> 1753921 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContext= Ex) [junit4] 2> 1753921 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find= system property or JNDI) [junit4] 2> 1753923 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 1753931 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1753952 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.1 [junit4] 2> 1754024 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1754033 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1754331 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchan= ge-rates.json, refreshInterval=3D1440. [junit4] 2> 1754363 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1754365 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1754399 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curre= ncy.xml [junit4] 2> 1754404 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curre= ncy.xml [junit4] 2> 1754407 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-e= xchange-rates.json [junit4] 2> 1754408 WARN (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1754408 WARN (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1754408 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-e= xchange-rates.json [junit4] 2> 1754408 WARN (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1754408 WARN (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1754409 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming d= efault properties: Can't find resource 'configsetprops.json' in classpath o= r '/configs/conf1', cwd=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-N= ightlyTests-5.4/solr/build/solr-core/test/J1 [junit4] 2> 1754409 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection x:collection1= ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration= from collection control_collection [junit4] 2> 1754409 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFa= ctory [junit4] 2> 1754410 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCor= e at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr= /build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D3= 8CC17F34E6FDAA-001/control-001/cores/collection1], dataDir=3D[null] [junit4] 2> 1754410 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Ad= ding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@65f1= db28 [junit4] 2> 1754410 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory = for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/b= uild/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-sla= ve/workspace/Lucene-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/temp= /solr.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6FDAA-001/control-00= 1/cores/collection1/data [junit4] 2> 1754411 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=3D= null new=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4= /solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenk= ins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/build/solr-core/test/= J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6FDAA-001/con= trol-001/cores/collection1/data/index/ [junit4] 2> 1754411 WARN (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '= /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/build= /solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/temp/sol= r.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6FDAA-001/control-001/co= res/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1754411 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory = for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/b= uild/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-sla= ve/workspace/Lucene-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/temp= /solr.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6FDAA-001/control-00= 1/cores/collection1/data/index [junit4] 2> 1754411 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping = class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.inde= x.MockRandomMergePolicy@2118e99d [junit4] 2> 1754412 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommi= t: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@6a915364= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@6afa00e4),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1754412 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = =3D 1 [junit4] 2> 1754416 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "nodistrib" [junit4] 2> 1754416 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "dedupe" [junit4] 2> 1754416 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distr= ibutedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1754416 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "stored_sig" [junit4] 2> 1754417 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distr= ibutedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1754417 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1754417 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1754417 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distr= ibutedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-= test-chain-implicit" [junit4] 2> 1754417 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defin= ed as default, creating implicit default [junit4] 2> 1754417 WARN (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in request= Handler {type =3D requestHandler,name =3D /dump,class =3D DumpRequestHandle= r,args =3D {defaults=3D{a=3DA,b=3DB}}} [junit4] 2> 1754420 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1754422 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1754423 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1754424 INFO (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:co= llection1] o.a.s.c.SolrCore Looking for old index directories to cleanup fo= r core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Night= lyTests-5.4/solr/build/solr-core/test/J1/../../../../../../../../../../x1/j= enkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/build/solr= -core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D38CC17F34E6F= DAA-001/control-001/cores/collection1/data/ [junit4] 2> 1754424 WARN (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:co= llection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Luc= ene-Solr-NightlyTests-5.4/solr/build/solr-core/test/J1/../../../../../../..= /../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/s= olr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest= _D38CC17F34E6FDAA-001/control-001/cores/collection1/data/ does not point to= a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1754426 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1754430 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/file= ,/admin/plugins,/admin/threads,/replication,/dump,/admin/properties,/schema= ,/admin/mbeans,/admin/luke,/update,/update/csv,/admin/system,/get,/update/j= son,/admin/segments,/admin/logging,/admin/ping,/update/json/docs,standard,/= config [junit4] 2> 1754432 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.= apache.solr.search.stats.LocalStatsCache [junit4] 2> 1754444 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:= org.apache.solr.update.UpdateLog [junit4] 2> 1754444 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D = defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVe= rsionBuckets=3D65536 [junit4] 2> 1754446 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1754446 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1754446 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping = class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: = minMergeSize=3D0, mergeFactor=3D10, maxMergeSize=3D318640695, maxMergeSizeF= orForcedMerge=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMerg= eDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D0.1] [junit4] 2> 1754447 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit:= commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@6a915364= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@6afa00e4),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1754447 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = =3D 1 [junit4] 2> 1754447 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@54786794[c= ollection1] main [junit4] 2> 1754447 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/= collections/control_collection [junit4] 2> 1754449 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.c.ZkStateReader path=3D/collections/control_c= ollection configName=3Dconf1 specified config exists in ZooKeeper [junit4] 2> 1754449 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-b= ased storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1754449 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperSt= orageIO with znodeBase: /configs/conf1 [junit4] 2> 1754449 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with ini= tArgs: {} [junit4] 2> 1754450 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.= json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1754450 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znod= e /configs/conf1/_rest_managed.json [junit4] 2> 1754450 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _r= est_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1754451 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered Managed= Resources [junit4] 2> 1754451 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved f= or 10000 [junit4] 2> 1754451 INFO (searcherExecutor-2487-thread-1-processing-n= :127.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1)= [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collectio= n1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5478679= 4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1754452 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version fie= ld to seed version buckets [junit4] 2> 1754452 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _vers= ion_ for 65536 version buckets from index [junit4] 2> 1754452 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, can= not seed version bucket highest value from index [junit4] 2> 1754452 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index= or recent updates, using new clock 1523275978978099200 [junit4] 2> 1754454 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets = with highest version 1523275978978099200 [junit4] 2> 1754455 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1754455 INFO (coreLoadExecutor-2486-thread-1-processing-n= :127.0.0.1:46789_) [n:127.0.0.1:46789_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1754456 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController Register replica - core:collection1 address:http://1= 27.0.0.1:46789 collection:control_collection shard:shard1 [junit4] 2> 1754457 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_e= lect/shard1/election [junit4] 2> 1754461 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/= control_collection/leader_elect/shard1/election/95204748547850246-core_node= 1-n_0000000000 [junit4] 2> 1754461 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard s= hard1 [junit4] 2> 1754463 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version= : 1 [junit4] 2> 1754464 INFO (zkCallback-649-thread-1-processing-n:127.0.= 0.1:46789_) [n:127.0.0.1:46789_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1754464 INFO (zkCallback-649-thread-2-processing-n:127.0.= 0.1:46789_) [n:127.0.0.1:46789_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1754468 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1754468 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1754468 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46789/collection1/ [junit4] 2> 1754468 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1754468 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.SyncStrategy http://127.0.0.1:46789/collection1/ has no replicas [junit4] 2> 1754469 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/= shard1 [junit4] 2> 1754471 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1754473 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:46789", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 1754474 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:= 46789/collection1/ shard1 [junit4] 2> 1754575 INFO (zkCallback-649-thread-2-processing-n:127.0.= 0.1:46789_) [n:127.0.0.1:46789_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1754575 INFO (zkCallback-649-thread-1-processing-n:127.0.= 0.1:46789_) [n:127.0.0.1:46789_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1754578 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController We are http://127.0.0.1:46789/collection1/ and leade= r is http://127.0.0.1:46789/collection1/ [junit4] 2> 1754579 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController No LogReplay needed for core=3Dcollection1 baseURL= =3Dhttp://127.0.0.1:46789 [junit4] 2> 1754579 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1754579 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController publishing state=3Dactive [junit4] 2> 1754579 INFO (coreZkRegister-2480-thread-1-processing-n:1= 27.0.0.1:46789_ r:core_node1 c:control_collection x:collection1 s:shard1) [= n:127.0.0.1:46789_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController numShards not found on descriptor - reading it from = system property [junit4] 2> 1754585 INFO (OverseerStateUpdate-95204748547850246-127.0= .0.1:46789_-n_0000000000) [n:127.0.0.1:46789_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "base_url":"http://127.0.0.1:46789", [junit4] 2> "node_name":"127.0.0.1:46789_", [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "core":"co [...truncated too long message...] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.jav= a:1130) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:615) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > 3) Thread[id=3D52355, name=3DzkCallback-675-thread-1, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:226) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:359) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:942) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1068) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1130) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:615) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > 4) Thread[id=3D52353, name=3DTEST-CollectionsAPIDistrib= utedZkTest.test-seed#[D38CC17F34E6FDAA]-SendThread(127.0.0.1:33845), state= =3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at java.lang.Thread.sleep(Native Method) [junit4] > at org.apache.zookeeper.client.StaticHostProvider.= next(StaticHostProvider.java:101) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.star= tConnect(ClientCnxn.java:940) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(= ClientCnxn.java:1003) [junit4] > 5) Thread[id=3D52354, name=3DTEST-CollectionsAPIDistrib= utedZkTest.test-seed#[D38CC17F34E6FDAA]-EventThread, state=3DWAITING, group= =3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.park(Loc= kSupport.java:186) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) [junit4] > at java.util.concurrent.LinkedBlockingQueue.take(L= inkedBlockingQueue.java:442) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:494) [junit4] > 6) Thread[id=3D53619, name=3DzkCallback-675-thread-2, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:226) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:359) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:942) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1068) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1130) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:615) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > =09at __randomizedtesting.SeedInfo.seed([D38CC17F34E6FDAA]= :0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There = are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=3D52353, name=3DTEST-CollectionsAPIDistrib= utedZkTest.test-seed#[D38CC17F34E6FDAA]-SendThread(127.0.0.1:33845), state= =3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at java.lang.Thread.sleep(Native Method) [junit4] > at org.apache.zookeeper.client.StaticHostProvider.= next(StaticHostProvider.java:101) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.star= tConnect(ClientCnxn.java:940) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(= ClientCnxn.java:1003) [junit4] > 2) Thread[id=3D52355, name=3DzkCallback-675-thread-1, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:226) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:359) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:942) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1068) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1130) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:615) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > 3) Thread[id=3D53619, name=3DzkCallback-675-thread-2, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:226) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:359) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:942) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1068) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1130) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:615) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > 4) Thread[id=3D53620, name=3DzkCallback-675-thread-3, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:226) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:359) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:942) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1068) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1130) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:615) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > 5) Thread[id=3D53621, name=3DzkCallback-675-thread-4, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:226) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:359) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:942) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1068) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1130) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:615) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > =09at __randomizedtesting.SeedInfo.seed([D38CC17F34E6FDAA]= :0) [junit4] Completed [256/551] on J1 in 116.28s, 1 test, 3 errors <<< FAIL= URES! [...truncated 896 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/build.xml:= 799: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/build.xml:= 736: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/build.xml:= 59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/build= .xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/solr/commo= n-build.xml:526: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/lucene/com= mon-build.xml:1452: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.4/lucene/com= mon-build.xml:1006: There were test failures: 551 suites (7 ignored), 2179 = tests, 2 suite-level errors, 1 error, 1024 ignored (16 assumptions) [seed: = D38CC17F34E6FDAA] Total time: 191 minutes 38 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_725_1438596694.1452709811000 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_725_1438596694.1452709811000--