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 1E51418E3E for ; Wed, 30 Dec 2015 07:26:03 +0000 (UTC) Received: (qmail 84408 invoked by uid 500); 30 Dec 2015 07:26:02 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 84337 invoked by uid 500); 30 Dec 2015 07:26:02 -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 84324 invoked by uid 99); 30 Dec 2015 07:26:01 -0000 Received: from Unknown (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 30 Dec 2015 07:26:01 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id 438BCC0B6A for ; Wed, 30 Dec 2015 07:26:01 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 2.002 X-Spam-Level: ** X-Spam-Status: No, score=2.002 tagged_above=-999 required=6.31 tests=[FSL_HELO_NON_FQDN_1=0.001, KAM_BADIPHTTP=2, SPF_PASS=-0.001, URIBL_BLOCKED=0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-us-west.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id vQFsfxt-CoAN for ; Wed, 30 Dec 2015 07:25:54 +0000 (UTC) Received: from serv1 (serv1.sd-datasolutions.de [188.138.57.78]) by mx1-us-west.apache.org (ASF Mail Server at mx1-us-west.apache.org) with ESMTPS id 7989D20CC7 for ; Wed, 30 Dec 2015 07:25:53 +0000 (UTC) Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1aEB8i-0003Q0-Uk for dev@lucene.apache.org; Wed, 30 Dec 2015 07:25:45 +0000 Date: Wed, 30 Dec 2015 07:25:31 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1795228002.61.1451460344951.JavaMail.jenkins@serv1> Subject: [JENKINS-EA] Lucene-Solr-trunk-Linux (64bit/jdk-9-ea+95) - Build # 15375 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_60_1596921018.1451460344901" X-Jenkins-Job: Lucene-Solr-trunk-Linux X-Jenkins-Result: FAILURE X-Priority: 5 (Low) ------=_Part_60_1596921018.1451460344901 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15375/ Java: 64bit/jdk-9-ea+95 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC -XX:= -CompactStrings 3 tests failed. 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=3D9406, name=3DTEST-CollectionsAPIDistrib= utedZkTest.test-seed#[A0FFB43F75647302]-EventThread, state=3DWAITING, group= =3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsaf= e.park(Native Method) at java.util.concurrent.locks.LockSupport.par= k(LockSupport.java:178) at java.util.concurrent.locks.AbstractQueue= dSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2061) = at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue= .java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(Clien= tCnxn.java:494) 2) Thread[id=3D9407, name=3DzkCallback-1770-thread-1, st= ate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] = at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concur= rent.locks.LockSupport.parkNanos(LockSupport.java:218) at java.util= .concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.ja= va:461) at java.util.concurrent.SynchronousQueue$TransferStack.tran= sfer(SynchronousQueue.java:362) at java.util.concurrent.Synchronous= Queue.poll(SynchronousQueue.java:937) at java.util.concurrent.Threa= dPoolExecutor.getTask(ThreadPoolExecutor.java:1082) at java.util.co= ncurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) = at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.j= ava:632) at java.lang.Thread.run(Thread.java:747) 3) Thread[id= =3D9662, name=3DzkCallback-1770-thread-4, state=3DTIMED_WAITING, group=3DTG= RP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.par= k(Native Method) at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) at java.util.concurrent.SynchronousQueue$Tr= ansferStack.awaitFulfill(SynchronousQueue.java:461) at java.util.co= ncurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)= at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.jav= a:937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoo= lExecutor.java:1082) at java.util.concurrent.ThreadPoolExecutor.run= Worker(ThreadPoolExecutor.java:1143) at java.util.concurrent.Thread= PoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.T= hread.run(Thread.java:747) 4) Thread[id=3D9405, name=3DTEST-CollectionsA= PIDistributedZkTest.test-seed#[A0FFB43F75647302]-SendThread(127.0.0.1:39012= ), state=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] = at java.lang.Thread.sleep(Native Method) at org.apache.zookeep= er.client.StaticHostProvider.next(StaticHostProvider.java:101) at o= rg.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)= at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:= 1003) 5) Thread[id=3D9661, name=3DzkCallback-1770-thread-3, state=3DTIME= D_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.int= ernal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks= .LockSupport.parkNanos(LockSupport.java:218) at java.util.concurren= t.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:461) = at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Synch= ronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll= (SynchronousQueue.java:937) at java.util.concurrent.ThreadPoolExecu= tor.getTask(ThreadPoolExecutor.java:1082) at java.util.concurrent.T= hreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.u= til.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) = at java.lang.Thread.run(Thread.java:747) 6) Thread[id=3D9660, nam= e=3DzkCallback-1770-thread-2, state=3DTIMED_WAITING, group=3DTGRP-Collectio= nsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Met= hod) at java.util.concurrent.locks.LockSupport.parkNanos(LockSuppor= t.java:218) at java.util.concurrent.SynchronousQueue$TransferStack.= awaitFulfill(SynchronousQueue.java:461) at java.util.concurrent.Syn= chronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at = java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:937) = at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.ja= va:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(Threa= dPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor= $Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Th= read.java:747) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 6 threads leaked from S= UITE scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest:=20 1) Thread[id=3D9406, name=3DTEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]-EventThread, state=3DWAITING, group=3DTGRP-Collection= sAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:178= ) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionO= bject.await(AbstractQueuedSynchronizer.java:2061) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueu= e.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:= 494) 2) Thread[id=3D9407, name=3DzkCallback-1770-thread-1, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:218) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:461) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:632) at java.lang.Thread.run(Thread.java:747) 3) Thread[id=3D9662, name=3DzkCallback-1770-thread-4, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:218) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:461) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:632) at java.lang.Thread.run(Thread.java:747) 4) Thread[id=3D9405, name=3DTEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]-SendThread(127.0.0.1:39012), state=3DTIMED_WAITING, g= roup=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=3D9661, name=3DzkCallback-1770-thread-3, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:218) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:461) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:632) at java.lang.Thread.run(Thread.java:747) 6) Thread[id=3D9660, name=3DzkCallback-1770-thread-2, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:218) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:461) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:632) at java.lang.Thread.run(Thread.java:747) =09at __randomizedtesting.SeedInfo.seed([A0FFB43F75647302]: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= =3D9407, name=3DzkCallback-1770-thread-1, state=3DTIMED_WAITING, group=3DTG= RP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.par= k(Native Method) at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) at java.util.concurrent.SynchronousQueue$Tr= ansferStack.awaitFulfill(SynchronousQueue.java:461) at java.util.co= ncurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)= at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.jav= a:937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoo= lExecutor.java:1082) at java.util.concurrent.ThreadPoolExecutor.run= Worker(ThreadPoolExecutor.java:1143) at java.util.concurrent.Thread= PoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.T= hread.run(Thread.java:747) 2) Thread[id=3D9662, name=3DzkCallback-1770-t= hread-4, state=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTes= t] at jdk.internal.misc.Unsafe.park(Native Method) at java.= util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:218) a= t java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(Synchron= ousQueue.java:461) at java.util.concurrent.SynchronousQueue$Transfe= rStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.= SynchronousQueue.poll(SynchronousQueue.java:937) at java.util.concu= rrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1082) at j= ava.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:11= 43) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoo= lExecutor.java:632) at java.lang.Thread.run(Thread.java:747) 3) = Thread[id=3D9405, name=3DTEST-CollectionsAPIDistributedZkTest.test-seed#[A0= FFB43F75647302]-SendThread(127.0.0.1:39012), state=3DTIMED_WAITING, group= =3DTGRP-CollectionsAPIDistributedZkTest] at java.lang.Thread.sleep(= Native Method) at org.apache.zookeeper.client.StaticHostProvider.ne= xt(StaticHostProvider.java:101) at org.apache.zookeeper.ClientCnxn$= SendThread.startConnect(ClientCnxn.java:940) at org.apache.zookeepe= r.ClientCnxn$SendThread.run(ClientCnxn.java:1003) 4) Thread[id=3D9661, n= ame=3DzkCallback-1770-thread-3, state=3DTIMED_WAITING, group=3DTGRP-Collect= ionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native M= ethod) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupp= ort.java:218) at java.util.concurrent.SynchronousQueue$TransferStac= k.awaitFulfill(SynchronousQueue.java:461) at java.util.concurrent.S= ynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) a= t java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:937) = at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.= java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(Thr= eadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecut= or$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(= Thread.java:747) 5) Thread[id=3D9660, name=3DzkCallback-1770-thread-2, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] = at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concu= rrent.locks.LockSupport.parkNanos(LockSupport.java:218) at java.uti= l.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.j= ava:461) at java.util.concurrent.SynchronousQueue$TransferStack.tra= nsfer(SynchronousQueue.java:362) at java.util.concurrent.Synchronou= sQueue.poll(SynchronousQueue.java:937) at java.util.concurrent.Thre= adPoolExecutor.getTask(ThreadPoolExecutor.java:1082) at java.util.c= oncurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) = at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.= java:632) at java.lang.Thread.run(Thread.java:747) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie = threads that couldn't be terminated: 1) Thread[id=3D9407, name=3DzkCallback-1770-thread-1, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:218) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:461) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:632) at java.lang.Thread.run(Thread.java:747) 2) Thread[id=3D9662, name=3DzkCallback-1770-thread-4, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:218) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:461) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:632) at java.lang.Thread.run(Thread.java:747) 3) Thread[id=3D9405, name=3DTEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]-SendThread(127.0.0.1:39012), state=3DTIMED_WAITING, g= roup=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) 4) Thread[id=3D9661, name=3DzkCallback-1770-thread-3, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:218) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:461) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:632) at java.lang.Thread.run(Thread.java:747) 5) Thread[id=3D9660, name=3DzkCallback-1770-thread-2, state=3DTIMED_WAIT= ING, group=3DTGRP-CollectionsAPIDistributedZkTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:218) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill= (SynchronousQueue.java:461) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Syn= chronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java= :937) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecut= or.java:1082) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:632) at java.lang.Thread.run(Thread.java:747) =09at __randomizedtesting.SeedInfo.seed([A0FFB43F75647302]:0) FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Error from server at http://127.0.0.1:35656/p_y/cv/awholynewcollection_0: n= on ok status: 500, message:Server Error Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error= from server at http://127.0.0.1:35656/p_y/cv/awholynewcollection_0: non ok= status: 500, message:Server Error =09at __randomizedtesting.SeedInfo.seed([A0FFB43F75647302:28AB8BE5DB981EFA]= :0) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:509) =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:638) =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:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:520) =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:965) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:940) =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:747) Build Log: [...truncated 10460 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistribu= tedZkTest_A0FFB43F75647302-001/init-core-data-001 [junit4] 2> 890301 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= A0FFB43F75647302]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false= ) and clientAuth (false) [junit4] 2> 890301 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= A0FFB43F75647302]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Settin= g hostContext system property: /p_y/cv [junit4] 2> 890303 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 890303 INFO (Thread-3202) [ ] o.a.s.c.ZkTestServer cli= ent port:0.0.0.0/0.0.0.0:0 [junit4] 2> 890303 INFO (Thread-3202) [ ] o.a.s.c.ZkTestServer Sta= rting server [junit4] 2> 890403 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ZkTestServer start zk server on port:= 39012 [junit4] 2> 890404 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 890404 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 890406 INFO (zkCallback-1732-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2aa= 0d8a9 name:ZooKeeperConnection Watcher:127.0.0.1:39012 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 890406 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 890406 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 890406 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 890408 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 890408 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 890409 INFO (zkCallback-1733-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@54a= a20ef name:ZooKeeperConnection Watcher:127.0.0.1:39012/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 890409 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 890409 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 890409 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections= /collection1 [junit4] 2> 890410 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections= /collection1/shards [junit4] 2> 890410 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections= /control_collection [junit4] 2> 890410 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections= /control_collection/shards [junit4] 2> 890411 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 890411 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/solrconfig.xml [junit4] 2> 890412 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 890412 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/schema.xml [junit4] 2> 890413 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig= .snippet.randomindexconfig.xml [junit4] 2> 890413 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 890413 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 890414 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/stopwords.txt [junit4] 2> 890414 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 890414 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/protwords.txt [junit4] 2> 890415 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 890415 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/currency.xml [junit4] 2> 890416 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 890416 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/enumsConfig.xml [junit4] 2> 890416 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 890416 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/open-exchange-rates.json [junit4] 2> 890417 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent= .txt [junit4] 2> 890417 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/mapping-ISOLatin1Accent.txt [junit4] 2> 890418 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 890418 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/old_synonyms.txt [junit4] 2> 890418 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 890418 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f1/synonyms.txt [junit4] 2> 890419 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 890419 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 890420 INFO (zkCallback-1734-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78e= 99947 name:ZooKeeperConnection Watcher:127.0.0.1:39012 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 890420 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 890420 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 890420 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 890421 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 890421 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 890421 INFO (zkCallback-1735-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@115= 204ad name:ZooKeeperConnection Watcher:127.0.0.1:39012/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 890421 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 890422 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 890422 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 890422 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/solrconfig.xml [junit4] 2> 890423 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 890423 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/schema.xml [junit4] 2> 890424 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 890424 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/enumsConfig.xml [junit4] 2> 890425 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig= .snippet.randomindexconfig.xml [junit4] 2> 890425 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 890425 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 890425 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/stopwords.txt [junit4] 2> 890426 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 890426 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/protwords.txt [junit4] 2> 890426 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 890426 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/currency.xml [junit4] 2> 890427 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 890427 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/open-exchange-rates.json [junit4] 2> 890428 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent= .txt [junit4] 2> 890428 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/mapping-ISOLatin1Accent.txt [junit4] 2> 890429 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 890429 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/old_synonyms.txt [junit4] 2> 890429 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 890429 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/synonyms.txt [junit4] 2> 890430 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1= /conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 890430 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/con= f2/elevate.xml [junit4] 2> 890492 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties = file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-= 001/control-001/cores/collection1 [junit4] 2> 890493 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 890507 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Serv= letContextHandler@186a4c79{/p_y/cv,null,AVAILABLE} [junit4] 2> 890509 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.e.j.s.ServerConnector Started ServerConnect= or@13230a18{HTTP/1.1,[http/1.1]}{127.0.0.1:41814} [junit4] 2> 890509 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.e.j.s.Server Started @892202ms [junit4] 2> 890509 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:= {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43= F75647302-001/tempDir-001/control/data, hostContext=3D/p_y/cv, hostPort=3D4= 1814, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspa= ce/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Col= lectionsAPIDistributedZkTest_A0FFB43F75647302-001/control-001/cores} [junit4] 2> 890509 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter= .init(): sun.misc.Launcher$AppClassLoader@deb6432 [junit4] 2> 890509 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLo= ader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/b= uild/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FF= B43F75647302-001/control-001' [junit4] 2> 890510 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.SolrResourceLoader JNDI not configure= d for solr (NoInitialContextEx) [junit4] 2> 890510 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.SolrResourceLoader solr home defaulte= d to 'solr/' (could not find system property or JNDI) [junit4] 2> 890510 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 890510 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 890511 INFO (zkCallback-1736-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@372= 79b2e name:ZooKeeperConnection Watcher:127.0.0.1:39012/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 890511 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 890512 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 890512 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml f= rom SolrHome (not found in ZooKeeper) [junit4] 2> 890512 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.SolrXmlConfig Loading container confi= guration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F756= 47302-001/control-001/solr.xml [junit4] 2> 890516 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CorePropertiesLocator Config-defined = core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/b= uild/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Collecti= onsAPIDistributedZkTest_A0FFB43F75647302-001/control-001/cores [junit4] 2> 890516 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer New CoreContainer 60435= 2829 [junit4] 2> 890516 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer Loading cores into Core= Container [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_= A0FFB43F75647302-001/control-001] [junit4] 2> 890516 WARN (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer Couldn't add files from= /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/= J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-001/con= trol-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/= solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTes= t_A0FFB43F75647302-001/control-001/lib [junit4] 2> 890516 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.h.c.HttpShardHandlerFactory created wit= h socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerH= ost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 21474836= 47,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries= : false, [junit4] 2> 890517 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateSha= rdHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D450= 00&retry=3Dtrue [junit4] 2> 890517 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.im= pl.Log4jLoggerFactory [junit4] 2> 890517 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.l.LogWatcher Registering Log Listener [= Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 890517 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.= 0.1:39012/solr [junit4] 2> 890517 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 890517 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 890517 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 890518 INFO (zkCallback-1739-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23e= 440c1 name:ZooKeeperConnection Watcher:127.0.0.1:39012 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 890518 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 890519 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 890519 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 890521 INFO (zkCallback-1740-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@264= 77674 name:ZooKeeperConnection Watcher:127.0.0.1:39012/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 890521 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 890522 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/qu= eue [junit4] 2> 890522 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/co= llection-queue-work [junit4] 2> 890524 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/co= llection-map-running [junit4] 2> 890525 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/co= llection-map-completed [junit4] 2> 890526 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/co= llection-map-failure [junit4] 2> 890527 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 890527 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.jso= n [junit4] 2> 890528 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstat= e.json [junit4] 2> 890530 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.js= on [junit4] 2> 890530 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ZkController Register node as live in= ZooKeeper:/live_nodes/127.0.0.1:41814_p_y%2Fcv [junit4] 2> 890530 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/= 127.0.0.1:41814_p_y%2Fcv [junit4] 2> 890531 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_el= ect [junit4] 2> 890532 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_el= ect/election [junit4] 2> 890532 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 890532 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.LeaderElector Joined leadership elect= ion with path: /overseer_elect/election/95122892536414214-127.0.0.1:41814_p= _y%2Fcv-n_0000000000 [junit4] 2> 890532 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.OverseerElectionContext I am going to= be the leader 127.0.0.1:41814_p_y%2Fcv [junit4] 2> 890532 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_el= ect/leader [junit4] 2> 890533 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.Overseer Overseer (id=3D9512289253641= 4214-127.0.0.1:41814_p_y%2Fcv-n_0000000000) starting [junit4] 2> 890534 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/qu= eue-work [junit4] 2> 890555 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Sta= rting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=3D= 10000 autoReplicaFailoverWaitAfterExpiration=3D10000 autoReplicaFailoverBad= NodeExpiration=3D60000 [junit4] 2> 890555 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ZkStateReader Updating cluster stat= e from ZooKeeper...=20 [junit4] 2> 890555 INFO (OverseerCollectionConfigSetProcessor-9512289= 2536414214-127.0.0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.OverseerTa= skProcessor Process current queue of overseer operations [junit4] 2> 890556 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.Overseer Starting to work o= n the main queue [junit4] 2> 890562 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer Security conf doesn't e= xist. Skipping setup for authorization module. [junit4] 2> 890562 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer No authentication plugi= n used. [junit4] 2> 890562 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CorePropertiesLocator Looking for cor= e definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspac= e/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Coll= ectionsAPIDistributedZkTest_A0FFB43F75647302-001/control-001/cores [junit4] 2> 890570 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor= : {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, schema= =3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigsetprops.j= son, collection=3Dcontrol_collection, coreNodeName=3D, dataDir=3Ddata/, sha= rd=3D} [junit4] 2> 890570 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CorePropertiesLocator Found core coll= ection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-= core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-= trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDist= ributedZkTest_A0FFB43F75647302-001/control-001/cores/collection1 [junit4] 2> 890570 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core de= finitions [junit4] 2> 890571 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.s.SolrDispatchFilter user.dir=3D/home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2 [junit4] 2> 890571 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter= .init() done [junit4] 2> 890571 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.ZkController publishing state=3Ddown [junit4] 2> 890571 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.ZkController numShards not found on descriptor - reading it from system = property [junit4] 2> 890572 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.Overseer processMessage: qu= eueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41814/p_y/cv", [junit4] 2> "node_name":"127.0.0.1:41814_p_y%2Fcv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 890572 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.ZkController look for our core node name [junit4] 2> 890572 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update sta= te numShards=3D1 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41814/p_y/cv", [junit4] 2> "node_name":"127.0.0.1:41814_p_y%2Fcv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 890572 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator build= ing a new cName: control_collection [junit4] 2> 890573 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning = new node to shard shard=3Dshard1 [junit4] 2> 890573 INFO (zkCallback-1740-thread-1) [ ] o.a.s.c.c.Z= kStateReader A cluster state change: [WatchedEvent state:SyncConnected type= :NodeDataChanged path:/clusterstate.json], has occurred - updating... (live= nodes size: [1]) [junit4] 2> 891572 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 891572 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.ZkController Check for collection zkNode:control_collection [junit4] 2> 891573 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.ZkController Collection zkNode exists [junit4] 2> 891573 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.c.ZkStateReader Load collection config from: [/collections/control_colle= ction] [junit4] 2> 891573 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.c.ZkStateReader path=3D[/collections/control_collection] [configName]=3D= [conf1] specified config exists in ZooKeeper [junit4] 2> 891573 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/= workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cl= oud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-001/control-001/cores/= collection1' [junit4] 2> 891573 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 891573 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system= property or JNDI) [junit4] 2> 891574 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 891579 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrConfig current version of requestparams : -1 [junit4] 2> 891583 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 891598 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 891605 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 891698 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchange-rate= s.json, refreshInterval=3D1440. [junit4] 2> 891704 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.IndexSchema default search field in schema is text [junit4] 2> 891705 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.IndexSchema unique key field: id [junit4] 2> 891709 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 891711 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 891712 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange= -rates.json [junit4] 2> 891713 WARN (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 891713 WARN (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 891713 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange= -rates.json [junit4] 2> 891713 WARN (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 891713 WARN (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 891714 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.ConfigSetProperties Did not find ConfigSet properties, assuming default = properties: Can't find resource 'configsetprops.json' in classpath or '/con= figs/conf1', cwd=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bui= ld/solr-core/test/J2 [junit4] 2> 891714 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.CoreContainer Creating SolrCore 'collection1' using configuration from c= ollection control_collection [junit4] 2> 891714 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 891714 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspa= ce/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Col= lectionsAPIDistributedZkTest_A0FFB43F75647302-001/control-001/cores/collect= ion1], dataDir=3D[/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucen= e-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Collections= APIDistributedZkTest_A0FFB43F75647302-001/control-001/cores/collection1/dat= a/] [junit4] 2> 891715 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Ser= ver: com.sun.jmx.mbeanserver.JmxMBeanServer@1f910424 [junit4] 2> 891715 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.CachingDirectoryFactory return new directory for /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../= ../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/t= est/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-001= /control-001/cores/collection1/data [junit4] 2> 891715 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrCore New index directory detected: old=3Dnull new=3D/home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../..= /../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr= -core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647= 302-001/control-001/cores/collection1/data/index/ [junit4] 2> 891715 WARN (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Luc= ene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../../.= ./home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/= J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-001/con= trol-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 891715 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.CachingDirectoryFactory return new directory for /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../= ../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/t= est/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-001= /control-001/cores/collection1/data/index [junit4] 2> 891715 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D6, maxMergeAtOnc= eExplicit=3D2, maxMergedSegmentMB=3D45.7763671875, floorSegmentMB=3D1.20117= 1875, forceMergeDeletesPctAllowed=3D1.5810918677270946, segmentsPerTier=3D4= 1.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D1.0 [junit4] 2> 891716 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@3b62eed4= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@1ef41570),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 891716 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 891716 INFO (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup= for core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspac= e/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Coll= ectionsAPIDistributedZkTest_A0FFB43F75647302-001/control-001/cores/collecti= on1/data/ [junit4] 2> 891716 WARN (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [ ] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-= trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/je= nkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/s= olr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-001/control-001/= cores/collection1/data/ does not point to a valid data directory; skipping = clean-up of old index directories. [junit4] 2> 891718 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodi= strib" [junit4] 2> 891718 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedu= pe" [junit4] 2> 891719 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactor= y into updateRequestProcessorChain "dedupe" [junit4] 2> 891719 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stor= ed_sig" [junit4] 2> 891719 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactor= y into updateRequestProcessorChain "stored_sig" [junit4] 2> 891719 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dist= rib-dup-test-chain-explicit" [junit4] 2> 891719 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dist= rib-dup-test-chain-implicit" [junit4] 2> 891719 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactor= y into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 891719 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrCore no updateRequestProcessorChain defined as default, creating imp= licit default [junit4] 2> 891719 WARN (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.RequestHandlers INVALID paramSet a in requestHandler {type =3D requestHa= ndler,name =3D /dump,class =3D DumpRequestHandler,args =3D {defaults=3D{a= =3DA,b=3DB}}} [junit4] 2> 891721 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 891722 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 891722 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 891723 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 891725 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/c= sv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/s= ystem,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins= ,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 891726 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.Lo= calStatsCache [junit4] 2> 891726 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Upd= ateLog [junit4] 2> 891726 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH nu= mRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 891727 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 891727 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 891728 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D33, maxMergeAtOn= ceExplicit=3D25, maxMergedSegmentMB=3D69.6484375, floorSegmentMB=3D1.326171= 875, forceMergeDeletesPctAllowed=3D22.82121966044305, segmentsPerTier=3D17.= 0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.7740180774153= 85 [junit4] 2> 891728 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@3b62eed4= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@1ef41570),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 891728 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 891728 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .s.SolrIndexSearcher Opening Searcher@34db16e1[collection1] main [junit4] 2> 891728 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.c.ZkStateReader Load collection config from: [/collections/control_colle= ction] [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.c.ZkStateReader path=3D[/collections/control_collection] [configName]=3D= [conf1] specified config exists in ZooKeeper [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestMa= nager with znodeBase: /configs/conf1 [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /co= nfigs/conf1 [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorage= IO:path=3D/configs/conf1 [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_mana= ged.json [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooK= eeperStorageIO:path=3D/configs/conf1 [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 891729 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 891730 INFO (searcherExecutor-3973-thread-1) [ ] o.a.s= .c.SolrCore [collection1] Registered new searcher Searcher@34db16e1[collect= ion1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 891730 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 891730 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.VersionInfo Refreshing highest value of _version_ for 65536 version buck= ets from index [junit4] 2> 891730 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.VersionInfo No terms found for _version_, cannot seed version bucket hig= hest value from index [junit4] 2> 891730 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.UpdateLog Could not find max version in index or recent updates, using n= ew clock 1521966282077896704 [junit4] 2> 891732 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .u.UpdateLog Took 2.0ms to seed version buckets with highest version 152196= 6282077896704 [junit4] 2> 891732 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.ZkController watch zkdir /configs/conf1 [junit4] 2> 891732 INFO (coreLoadExecutor-3972-thread-1) [ ] o.a.s= .c.CoreContainer registering core: collection1 [junit4] 2> 891732 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ZkController Register replica - core:collection1 address:http://127.0.0.1:= 41814/p_y/cv collection:control_collection shard:shard1 [junit4] 2> 891733 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .c.SolrZkClient makePath: /collections/control_collection/leader_elect/shar= d1/election [junit4] 2> 891734 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .LeaderElector Joined leadership election with path: /collections/control_c= ollection/leader_elect/shard1/election/95122892536414214-core_node1-n_00000= 00000 [junit4] 2> 891734 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ShardLeaderElectionContext Running the leader process for shard=3Dshard1 a= nd weAreReplacement=3Dfalse and leaderVoteWait=3D180000 [junit4] 2> 891736 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 891736 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 891736 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .SyncStrategy Sync replicas to http://127.0.0.1:41814/p_y/cv/collection1/ [junit4] 2> 891736 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 891736 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.Overseer processMessage: qu= eueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version= : 1 [junit4] 2> 891736 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .SyncStrategy http://127.0.0.1:41814/p_y/cv/collection1/ has no replicas [junit4] 2> 891737 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 891737 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 891738 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ShardLeaderElectionContext I am the new leader: http://127.0.0.1:41814/p_y= /cv/collection1/ shard1 [junit4] 2> 891739 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.Overseer processMessage: qu= eueSize: 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:41814/p_y/cv", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 891840 INFO (zkCallback-1740-thread-1) [ ] o.a.s.c.c.Z= kStateReader A cluster state change: [WatchedEvent state:SyncConnected type= :NodeDataChanged path:/clusterstate.json], has occurred - updating... (live= nodes size: [1]) [junit4] 2> 891889 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ZkController We are http://127.0.0.1:41814/p_y/cv/collection1/ and leader = is http://127.0.0.1:41814/p_y/cv/collection1/ [junit4] 2> 891890 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ZkController No LogReplay needed for core=3Dcollection1 baseURL=3Dhttp://1= 27.0.0.1:41814/p_y/cv [junit4] 2> 891890 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ZkController I am the leader, no recovery necessary [junit4] 2> 891890 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ZkController publishing state=3Dactive [junit4] 2> 891890 INFO (coreZkRegister-3966-thread-1) [ ] o.a.s.c= .ZkController numShards not found on descriptor - reading it from system pr= operty [junit4] 2> 891890 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.Overseer processMessage: qu= eueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41814/p_y/cv", [junit4] 2> "node_name":"127.0.0.1:41814_p_y%2Fcv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 891891 INFO (OverseerStateUpdate-95122892536414214-127.0.= 0.1:41814_p_y%2Fcv-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update sta= te numShards=3D1 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41814/p_y/cv", [junit4] 2> "node_name":"127.0.0.1:41814_p_y%2Fcv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 891992 INFO (zkCallback-1740-thread-2) [ ] o.a.s.c.c.Z= kStateReader A cluster state change: [WatchedEvent state:SyncConnected type= :NodeDataChanged path:/clusterstate.json], has occurred - updating... (live= nodes size: [1]) [junit4] 2> 892072 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 892073 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 892073 INFO (zkCallback-1742-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@553= 00be8 name:ZooKeeperConnection Watcher:127.0.0.1:39012/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 892073 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 892074 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 892074 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ZkStateReader Updating cluster stat= e from ZooKeeper...=20 [junit4] 2> 892074 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire ses= sions:false cause connection loss:false [junit4] 2> 892159 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties = file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-= 001/shard-1-001/cores/collection1 [junit4] 2> 892160 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create = jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/b= uild/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FF= B43F75647302-001/shard-1-001 [junit4] 2> 892161 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 892162 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Serv= letContextHandler@6f9892c1{/p_y/cv,null,AVAILABLE} [junit4] 2> 892162 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.e.j.s.ServerConnector Started ServerConnect= or@3a8fa156{HTTP/1.1,[http/1.1]}{127.0.0.1:35656} [junit4] 2> 892163 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.e.j.s.Server Started @893856ms [junit4] 2> 892163 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:= {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43= F75647302-001/tempDir-001/jetty1, solrconfig=3Dsolrconfig.xml, hostContext= =3D/p_y/cv, hostPort=3D35656, coreRootDirectory=3D/home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../..= /../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-001/s= hard-1-001/cores} [junit4] 2> 892163 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter= .init(): sun.misc.Launcher$AppClassLoader@deb6432 [junit4] 2> 892163 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLo= ader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/b= uild/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FF= B43F75647302-001/shard-1-001' [junit4] 2> 892164 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.SolrResourceLoader JNDI not configure= d for solr (NoInitialContextEx) [junit4] 2> 892164 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.SolrResourceLoader solr home defaulte= d to 'solr/' (could not find system property or JNDI) [junit4] 2> 892164 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 892164 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 892165 INFO (zkCallback-1743-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@80d= a937 name:ZooKeeperConnection Watcher:127.0.0.1:39012/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 892165 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 892166 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 892166 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml f= rom SolrHome (not found in ZooKeeper) [junit4] 2> 892166 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.SolrXmlConfig Loading container confi= guration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F756= 47302-001/shard-1-001/solr.xml [junit4] 2> 892170 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CorePropertiesLocator Config-defined = core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/b= uild/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Collecti= onsAPIDistributedZkTest_A0FFB43F75647302-001/shard-1-001/cores [junit4] 2> 892171 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer New CoreContainer 24807= 6000 [junit4] 2> 892171 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer Loading cores into Core= Container [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_= A0FFB43F75647302-001/shard-1-001] [junit4] 2> 892171 WARN (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer Couldn't add files from= /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/= J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_A0FFB43F75647302-001/sha= rd-1-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/= solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTes= t_A0FFB43F75647302-001/shard-1-001/lib [junit4] 2> 892171 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.h.c.HttpShardHandlerFactory created wit= h socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerH= ost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 21474836= 47,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries= : false, [junit4] 2> 892172 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateSha= rdHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D450= 00&retry=3Dtrue [junit4] 2> 892172 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.im= pl.Log4jLoggerFactory [junit4] 2> 892172 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.l.LogWatcher Registering Log Listener [= Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 892172 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.= 0.1:39012/solr [junit4] 2> 892173 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 892173 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCreden= tialsProvider [junit4] 2> 892173 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 892174 INFO (zkCallback-1746-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c50= f49c name:ZooKeeperConnection Watcher:127.0.0.1:39012 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 892174 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 892174 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLPro= vider [junit4] 2> 892174 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Waiting for clien= t to connect to ZooKeeper [junit4] 2> 892179 INFO (zkCallback-1747-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4d8= a1a04 name:ZooKeeperConnection Watcher:127.0.0.1:39012/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 892179 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ConnectionManager Client is connect= ed to ZooKeeper [junit4] 2> 892181 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.ZkStateReader Updating cluster stat= e from ZooKeeper...=20 [junit4] 2> 893184 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.ZkController Register node as live in= ZooKeeper:/live_nodes/127.0.0.1:35656_p_y%2Fcv [junit4] 2> 893184 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/= 127.0.0.1:35656_p_y%2Fcv [junit4] 2> 893185 INFO (zkCallback-1742-thread-1) [ ] o.a.s.c.c.Z= kStateReader A live node change: [WatchedEvent state:SyncConnected type:Nod= eChildrenChanged path:/live_nodes], has occurred - updating... (live nodes = size: [1]) [junit4] 2> 893185 INFO (zkCallback-1740-thread-2) [ ] o.a.s.c.c.Z= kStateReader A live node change: [WatchedEvent state:SyncConnected type:Nod= eChildrenChanged path:/live_nodes], has occurred - updating... (live nodes = size: [1]) [junit4] 2> 893185 INFO (zkCallback-1747-thread-1) [ ] o.a.s.c.c.Z= kStateReader A live node change: [WatchedEvent state:SyncConnected type:Nod= eChildrenChanged path:/live_nodes], has occurred - updating... (live nodes = size: [1]) [junit4] 2> 893185 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 893185 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.LeaderElector Joined leadership elect= ion with path: /overseer_elect/election/95122892536414218-127.0.0.1:35656_p= _y%2Fcv-n_0000000001 [junit4] 2> 893186 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.LeaderElector Watching path /overseer= _elect/election/95122892536414214-127.0.0.1:41814_p_y%2Fcv-n_0000000000 to = know if I could be the leader [junit4] 2> 893194 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer Security conf doesn't e= xist. Skipping setup for authorization module. [junit4] 2> 893194 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0FFB43F75647302]) [ ] o.a.s.c.CoreContainer No authentication plugi= n used. [junit4] 2> 893195 INFO (TEST-CollectionsAPIDistributedZkTest.test-se= ed#[A0F [...truncated too long message...] it4] > 3) Thread[id=3D9662, name=3DzkCallback-1770-thread-4, state=3D= TIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:461) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:937) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1082) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1143) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:632) [junit4] > at java.lang.Thread.run(Thread.java:747) [junit4] > 4) Thread[id=3D9405, name=3DTEST-CollectionsAPIDistribu= tedZkTest.test-seed#[A0FFB43F75647302]-SendThread(127.0.0.1:39012), 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=3D9661, name=3DzkCallback-1770-thread-3, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:461) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:937) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1082) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1143) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:632) [junit4] > at java.lang.Thread.run(Thread.java:747) [junit4] > 6) Thread[id=3D9660, name=3DzkCallback-1770-thread-2, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:461) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:937) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1082) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1143) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:632) [junit4] > at java.lang.Thread.run(Thread.java:747) [junit4] > =09at __randomizedtesting.SeedInfo.seed([A0FFB43F75647302]= :0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There = are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=3D9407, name=3DzkCallback-1770-thread-1, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:461) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:937) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1082) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1143) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:632) [junit4] > at java.lang.Thread.run(Thread.java:747) [junit4] > 2) Thread[id=3D9662, name=3DzkCallback-1770-thread-4, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:461) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:937) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1082) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1143) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:632) [junit4] > at java.lang.Thread.run(Thread.java:747) [junit4] > 3) Thread[id=3D9405, name=3DTEST-CollectionsAPIDistribu= tedZkTest.test-seed#[A0FFB43F75647302]-SendThread(127.0.0.1:39012), 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] > 4) Thread[id=3D9661, name=3DzkCallback-1770-thread-3, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:461) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:937) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1082) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1143) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:632) [junit4] > at java.lang.Thread.run(Thread.java:747) [junit4] > 5) Thread[id=3D9660, name=3DzkCallback-1770-thread-2, s= tate=3DTIMED_WAITING, group=3DTGRP-CollectionsAPIDistributedZkTest] [junit4] > at jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:218) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.awaitFulfill(SynchronousQueue.java:461) [junit4] > at java.util.concurrent.SynchronousQueue$TransferS= tack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(Sync= hronousQueue.java:937) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask= (ThreadPoolExecutor.java:1082) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWork= er(ThreadPoolExecutor.java:1143) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.= run(ThreadPoolExecutor.java:632) [junit4] > at java.lang.Thread.run(Thread.java:747) [junit4] > =09at __randomizedtesting.SeedInfo.seed([A0FFB43F75647302]= :0) [junit4] Completed [288/573 (1!)] on J2 in 52.91s, 1 test, 3 errors <<< = FAILURES! [...truncated 894 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:784: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:728: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following= error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The fol= lowing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:516: = The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:142= 4: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:981= : There were test failures: 573 suites (8 ignored), 2284 tests, 2 suite-lev= el errors, 1 error, 908 ignored (67 assumptions) [seed: A0FFB43F75647302] Total time: 36 minutes 48 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_60_1596921018.1451460344901 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_60_1596921018.1451460344901--