From dev-return-310361-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Thu Feb 1 14:50:26 2018 Return-Path: X-Original-To: archive-asf-public@eu.ponee.io Delivered-To: archive-asf-public@eu.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by mx-eu-01.ponee.io (Postfix) with ESMTP id 68015180652 for ; Thu, 1 Feb 2018 14:50:26 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id 57C74160C56; Thu, 1 Feb 2018 13:50:26 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id DD8D8160C26 for ; Thu, 1 Feb 2018 14:50:23 +0100 (CET) Received: (qmail 76158 invoked by uid 500); 1 Feb 2018 13:50:17 -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 76128 invoked by uid 99); 1 Feb 2018 13:50:17 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd2-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 01 Feb 2018 13:50:17 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd2-us-west.apache.org (ASF Mail Server at spamd2-us-west.apache.org) with ESMTP id 825761A6674 for ; Thu, 1 Feb 2018 13:50:16 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd2-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -0.3 X-Spam-Level: X-Spam-Status: No, score=-0.3 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-us.apache.org ([10.40.0.8]) by localhost (spamd2-us-west.apache.org [10.40.0.9]) (amavisd-new, port 10024) with ESMTP id iZ6uMW0NCuth for ; Thu, 1 Feb 2018 13:49:59 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [88.99.242.108]) by mx1-lw-us.apache.org (ASF Mail Server at mx1-lw-us.apache.org) with ESMTP id BEC995F296 for ; Thu, 1 Feb 2018 13:49:58 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id 50B3A10805A9 for ; Thu, 1 Feb 2018 13:49:57 +0000 (UTC) Date: Thu, 1 Feb 2018 13:47:50 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <346486015.47.1517492997331.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <2024286266.23.1517453271240.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <2024286266.23.1517453271240.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build # 1655 - Still Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_46_484114832.1517492997281" X-Jenkins-Job: Lucene-Solr-master-Solaris X-Jenkins-Result: UNSTABLE ------=_Part_46_484114832.1517492997281 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1655/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC 1 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithS= ecureImpersonation Error Message: 2 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWit= hSecureImpersonation: 1) Thread[id=3D12357, name=3Djetty-launcher-1977-= thread-2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWith= SecureImpersonation] at sun.misc.Unsafe.park(Native Method) = at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) = at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireS= haredNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concu= rrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueued= Synchronizer.java:1328) at java.util.concurrent.CountDownLatch.awai= t(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperCl= ient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)= at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) = at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeg= round(GetDataBuilderImpl.java:288) at org.apache.curator.framework.= imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org= .apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImp= l.java:41) at org.apache.curator.framework.recipes.shared.SharedVal= ue.readValue(SharedValue.java:244) at org.apache.curator.framework.= recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.a= pache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.ja= va:61) at org.apache.curator.framework.imps.NamespaceWatcher.proces= s(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$Even= tThread.processEvent(ClientCnxn.java:531) at org.apache.zookeeper.C= lientCnxn$EventThread.run(ClientCnxn.java:506) 2) Thread[id=3D12352, nam= e=3Djetty-launcher-1977-thread-1-EventThread, state=3DTIMED_WAITING, group= =3DTGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.pa= rk(Native Method) at java.util.concurrent.locks.LockSupport.parkNan= os(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueu= edSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) = at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireS= haredNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concu= rrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.c= urator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(Curator= ZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithR= etry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDa= taBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.= apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl= .java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.= forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework= .recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.= apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.= java:44) at org.apache.curator.framework.recipes.shared.SharedValue= $1.process(SharedValue.java:61) at org.apache.curator.framework.imp= s.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.= zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531) = at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from S= UITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:= =20 1) Thread[id=3D12357, name=3Djetty-launcher-1977-thread-2-EventThread, s= tate=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireS= haredNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquire= SharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:27= 7) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConn= ectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeg= round(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(Get= DataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(Get= DataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValu= e(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$1= 00(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.proces= s(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(Names= paceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientC= nxn.java:531) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:= 506) 2) Thread[id=3D12352, name=3Djetty-launcher-1977-thread-1-EventThread, s= tate=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireS= haredNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquire= SharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:27= 7) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConn= ectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeg= round(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(Get= DataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(Get= DataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValu= e(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$1= 00(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.proces= s(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(Names= paceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientC= nxn.java:531) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:= 506) =09at __randomizedtesting.SeedInfo.seed([9135ED1CEA3F5160]:0) Build Log: [...truncated 12043 lines...] [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithSecureImpersonati= on [junit4] 2> 1157701 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sa= nity checks: test.solr.allowed.securerandom=3Dnull & java.security.egd=3Dfi= le:/dev/./urandom [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-So= lr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrClou= dWithSecureImpersonation_9135ED1CEA3F5160-001/init-core-data-001 [junit4] 2> 1157731 WARN (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSe= archers: numOpens=3D1 numCloses=3D1 [junit4] 2> 1157731 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFiel= ds (NUMERIC_POINTS_SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dtrue [junit4] 2> 1157733 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl = (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reas= on=3D, value=3DNaN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 1157902 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Startin= g cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-master= -Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecu= reImpersonation_9135ED1CEA3F5160-001/tempDir-001 [junit4] 2> 1157902 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TES= T SERVER [junit4] 2> 1157903 INFO (Thread-5805) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1157903 INFO (Thread-5805) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1157910 ERROR (Thread-5805) [ ] o.a.z.s.ZooKeeperServer= ZKShutdownHandler is not registered, so ZooKeeper server won't take any ac= tion on ERROR or SHUTDOWN server state changes [junit4] 2> 1158003 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.c.ZkTestServer start zk server= on port:58506 [junit4] 2> 1158017 INFO (zkConnectionManagerCallback-1980-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1158031 INFO (jetty-launcher-1977-thread-1) [ ] o.e.j.= s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00,= git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1158033 INFO (jetty-launcher-1977-thread-1) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 1158033 INFO (jetty-launcher-1977-thread-1) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 1158033 INFO (jetty-launcher-1977-thread-1) [ ] o.e.j.= s.session Scavenging every 660000ms [junit4] 2> 1158036 INFO (jetty-launcher-1977-thread-1) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@59353eae{/solr,nul= l,AVAILABLE} [junit4] 2> 1158037 INFO (jetty-launcher-1977-thread-1) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@7f83e19e{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:59765} [junit4] 2> 1158037 INFO (jetty-launcher-1977-thread-1) [ ] o.e.j.= s.Server Started @1161337ms [junit4] 2> 1158037 INFO (jetty-launcher-1977-thread-1) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D59= 765} [junit4] 2> 1158037 ERROR (jetty-launcher-1977-thread-1) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1158037 INFO (jetty-launcher-1977-thread-1) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.= 0 [junit4] 2> 1158037 INFO (jetty-launcher-1977-thread-1) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1158037 INFO (jetty-launcher-1977-thread-1) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1158037 INFO (jetty-launcher-1977-thread-1) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-01T12:14:51.778Z [junit4] 2> 1158036 INFO (jetty-launcher-1977-thread-2) [ ] o.e.j.= s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00,= git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1158040 INFO (jetty-launcher-1977-thread-2) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 1158040 INFO (jetty-launcher-1977-thread-2) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 1158040 INFO (jetty-launcher-1977-thread-2) [ ] o.e.j.= s.session Scavenging every 600000ms [junit4] 2> 1158040 INFO (jetty-launcher-1977-thread-2) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56895767{/solr,nul= l,AVAILABLE} [junit4] 2> 1158041 INFO (jetty-launcher-1977-thread-2) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@38712084{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:48706} [junit4] 2> 1158041 INFO (jetty-launcher-1977-thread-2) [ ] o.e.j.= s.Server Started @1161341ms [junit4] 2> 1158041 INFO (jetty-launcher-1977-thread-2) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D48= 706} [junit4] 2> 1158041 ERROR (jetty-launcher-1977-thread-2) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1158041 INFO (jetty-launcher-1977-thread-2) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.= 0 [junit4] 2> 1158041 INFO (jetty-launcher-1977-thread-2) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1158041 INFO (jetty-launcher-1977-thread-2) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1158041 INFO (jetty-launcher-1977-thread-2) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-01T12:14:51.782Z [junit4] 2> 1158052 INFO (zkConnectionManagerCallback-1982-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1158052 INFO (zkConnectionManagerCallback-1984-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1158056 INFO (jetty-launcher-1977-thread-2) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1158062 INFO (jetty-launcher-1977-thread-1) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1158067 INFO (jetty-launcher-1977-thread-2) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:58506/solr [junit4] 2> 1158079 INFO (jetty-launcher-1977-thread-1) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:58506/solr [junit4] 2> 1158095 INFO (zkConnectionManagerCallback-1988-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1158102 INFO (zkConnectionManagerCallback-1992-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1158111 INFO (zkConnectionManagerCallback-1996-thread-1-p= rocessing-n:127.0.0.1:48706_solr) [n:127.0.0.1:48706_solr ] o.a.s.c.c.Co= nnectionManager zkClient has connected [junit4] 2> 1158122 INFO (zkConnectionManagerCallback-1994-thread-1-p= rocessing-n:127.0.0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.Co= nnectionManager zkClient has connected [junit4] 2> 1158151 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1158152 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader= 127.0.0.1:59765_solr [junit4] 2> 1158152 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.c.Overseer Overseer (id=3D72123289957826565-127.0.0.1= :59765_solr-n_0000000000) starting [junit4] 2> 1158172 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:59765_solr [junit4] 2> 1158231 INFO (zkCallback-1993-thread-1-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1158296 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: o= rg.apache.solr.security.HttpParamDelegationTokenPlugin [junit4] 2> 1158297 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (1) [junit4] 2> 1158300 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1158303 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:48706_solr [junit4] 2> 1158303 WARN (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder org.apache.solr.client.so= lrj.impl.Krb5HttpClientBuilder is configured without specifying system prop= erty 'java.security.auth.login.config' [junit4] 2> 1158310 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.s.KerberosPlugin Params: {solr.kerberos.impersonator.= user.noGroups.hosts=3D*, solr.kerberos.impersonator.user.bogusGroup.groups= =3D__some_bogus_group, token.valid=3D30, cookie.domain=3D127.0.0.1, solr.ke= rberos.impersonator.user.anyHostAnyUser.groups=3D*, solr.kerberos.impersona= tor.user.bogusGroup.hosts=3D*, solr.kerberos.impersonator.user.anyHostAnyUs= er.hosts=3D*, token.validity=3D36000, solr.kerberos.impersonator.user.noHos= ts.groups=3D*, type=3Dkerberos, solr.kerberos.impersonator.user.localHostAn= yGroup.groups=3D*, delegation-token.token-kind=3Dsolr-dt, solr.kerberos.imp= ersonator.user.wrongHost.hosts=3D1.1.1.1.1.1, cookie.path=3D/, zk-dt-secret= -manager.znodeWorkingPath=3Dsolr/security/zkdtsm, solr.kerberos.impersonato= r.user.localHostAnyGroup.hosts=3Dserv1-vm3,localhost,127.0.0.1, signer.secr= et.provider.zookeeper.path=3D/token, solr.kerberos.impersonator.user.anyHos= tUsersGroup.groups=3Dstaff, zk-dt-secret-manager.enable=3Dtrue, solr.kerber= os.impersonator.user.wrongHost.groups=3D*, kerberos.name.rules=3DDEFAULT, s= igner.secret.provider=3Dzookeeper, solr.kerberos.impersonator.user.anyHostU= sersGroup.hosts=3D*} [junit4] 2> 1158321 INFO (zkCallback-1993-thread-2-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1158406 INFO (zkCallback-1995-thread-1-processing-n:127.0= .0.1:48706_solr) [n:127.0.0.1:48706_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1158444 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 1158505 INFO (jetty-launcher-1977-thread-1-EventThread) [= n:127.0.0.1:59765_solr ] o.a.c.f.s.ConnectionStateManager State change: = CONNECTED [junit4] 2> 1158568 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: o= rg.apache.solr.security.HttpParamDelegationTokenPlugin [junit4] 2> 1158575 WARN (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder org.apache.solr.client.so= lrj.impl.Krb5HttpClientBuilder is configured without specifying system prop= erty 'java.security.auth.login.config' [junit4] 2> 1158630 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.s.KerberosPlugin Params: {solr.kerberos.impersonator.= user.noGroups.hosts=3D*, solr.kerberos.impersonator.user.bogusGroup.groups= =3D__some_bogus_group, token.valid=3D30, cookie.domain=3D127.0.0.1, solr.ke= rberos.impersonator.user.anyHostAnyUser.groups=3D*, solr.kerberos.impersona= tor.user.bogusGroup.hosts=3D*, solr.kerberos.impersonator.user.anyHostAnyUs= er.hosts=3D*, token.validity=3D36000, solr.kerberos.impersonator.user.noHos= ts.groups=3D*, type=3Dkerberos, solr.kerberos.impersonator.user.localHostAn= yGroup.groups=3D*, delegation-token.token-kind=3Dsolr-dt, solr.kerberos.imp= ersonator.user.wrongHost.hosts=3D1.1.1.1.1.1, cookie.path=3D/, zk-dt-secret= -manager.znodeWorkingPath=3Dsolr/security/zkdtsm, solr.kerberos.impersonato= r.user.localHostAnyGroup.hosts=3Dserv1-vm3,localhost,127.0.0.1, signer.secr= et.provider.zookeeper.path=3D/token, solr.kerberos.impersonator.user.anyHos= tUsersGroup.groups=3Dstaff, zk-dt-secret-manager.enable=3Dtrue, solr.kerber= os.impersonator.user.wrongHost.groups=3D*, kerberos.name.rules=3DDEFAULT, s= igner.secret.provider=3Dzookeeper, solr.kerberos.impersonator.user.anyHostU= sersGroup.hosts=3D*} [junit4] 2> 1158645 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 1158680 INFO (jetty-launcher-1977-thread-2-EventThread) [= n:127.0.0.1:48706_solr ] o.a.c.f.s.ConnectionStateManager State change: = CONNECTED [junit4] 2> 1158916 WARN (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder org.apache.solr.client.so= lrj.impl.Krb5HttpClientBuilder is configured without specifying system prop= erty 'java.security.auth.login.config' [junit4] 2> 1158936 WARN (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder org.apache.solr.client.so= lrj.impl.Krb5HttpClientBuilder is configured without specifying system prop= erty 'java.security.auth.login.config' [junit4] 2> 1159005 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48706.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@1b3ca7b [junit4] 2> 1159021 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59765.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@1b3ca7b [junit4] 2> 1159022 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48706.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@1b3ca7b [junit4] 2> 1159022 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48706.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@1b3ca7b [junit4] 2> 1159023 INFO (jetty-launcher-1977-thread-2) [n:127.0.0.1:= 48706_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build= /solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_913= 5ED1CEA3F5160-001/tempDir-001/node2/. [junit4] 2> 1159032 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59765.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@1b3ca7b [junit4] 2> 1159032 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59765.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@1b3ca7b [junit4] 2> 1159033 INFO (jetty-launcher-1977-thread-1) [n:127.0.0.1:= 59765_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build= /solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_913= 5ED1CEA3F5160-001/tempDir-001/node1/. [junit4] 2> 1159066 INFO (zkConnectionManagerCallback-2002-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1159092 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidProxyUser-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCase= J4 ###Starting testProxyInvalidProxyUser [junit4] 2> 1159129 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidProxyUser-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.Response= ProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3Dbar&p= =3Dbar&t=3Dtest&e=3D1517523292845&s=3DwlmIA1qpJB95PLMqXoee3faJQfs=3D"; Path= =3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2561 22:14:52 GMT; HttpOnl= y". Invalid 'expires' attribute: ., 01- . .-2561 22:14:52 GMT [junit4] 2> 1159129 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidProxyUser-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCase= J4 ###Ending testProxyInvalidProxyUser [junit4] 2> 1159150 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNullProxyUser-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyNullProxyUser [junit4] 2> 1159151 INFO (qtp1510026401-12314) [ ] o.a.s.s.SolrDis= patchFilter Error authenticating [junit4] 2> java.lang.IllegalArgumentException: userName is NULL, empt= y or contains a '&' [junit4] 2> =09at org.apache.hadoop.security.authentication.util.AuthT= oken.checkForIllegalArgument(AuthToken.java:87) [junit4] 2> =09at org.apache.hadoop.security.authentication.util.AuthT= oken.(AuthToken.java:70) [junit4] 2> =09at org.apache.hadoop.security.authentication.server.Aut= henticationToken.(AuthenticationToken.java:58) [junit4] 2> =09at org.apache.solr.security.HttpParamDelegationTokenPlu= gin$HttpParamDelegationTokenAuthenticationHandler$HttpParamAuthenticationHa= ndler.authenticate(HttpParamDelegationTokenPlugin.java:213) [junit4] 2> =09at org.apache.hadoop.security.token.delegation.web.Dele= gationTokenAuthenticationHandler.authenticate(DelegationTokenAuthentication= Handler.java:348) [junit4] 2> =09at org.apache.solr.security.RequestContinuesRecorderAut= henticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandl= er.java:69) [junit4] 2> =09at org.apache.hadoop.security.authentication.server.Aut= henticationFilter.doFilter(AuthenticationFilter.java:518) [junit4] 2> =09at org.apache.solr.security.DelegationTokenKerberosFilt= er.doFilter(DelegationTokenKerberosFilter.java:134) [junit4] 2> =09at org.apache.solr.security.HttpParamDelegationTokenPlu= gin$HttpParamToRequestFilter.doFilter(HttpParamDelegationTokenPlugin.java:2= 45) [junit4] 2> =09at org.apache.solr.security.KerberosPlugin.doAuthentica= te(KerberosPlugin.java:243) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.authentic= ateRequest(SolrDispatchFilter.java:449) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:349) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:326) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:533) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:1595) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1253) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:168) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:473) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:1564) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:166) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1155) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.handl= e(ScopedHandler.java:141) [junit4] 2> =09at org.eclipse.jetty.server.handler.gzip.GzipHandler.ha= ndle(GzipHandler.java:527) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:132) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 30) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:347) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:256) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:279) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:102) [junit4] 2> =09at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEn= dPoint.java:124) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.doProduce(EatWhatYouKill.java:247) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.produce(EatWhatYouKill.java:140) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.run(EatWhatYouKill.java:131) [junit4] 2> =09at org.eclipse.jetty.util.thread.ReservedThreadExecutor= $ReservedThread.run(ReservedThreadExecutor.java:382) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:708) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:626) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 1159151 WARN (qtp1510026401-12314) [ ] o.e.j.s.HttpCha= nnel /solr/admin/collections [junit4] 2> org.apache.solr.common.SolrException: Error during request= authentication,=20 [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.authentic= ateRequest(SolrDispatchFilter.java:455) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:349) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:326) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:533) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:1595) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1253) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:168) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:473) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:1564) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:166) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1155) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.handl= e(ScopedHandler.java:141) [junit4] 2> =09at org.eclipse.jetty.server.handler.gzip.GzipHandler.ha= ndle(GzipHandler.java:527) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:132) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 30) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:347) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:256) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:279) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:102) [junit4] 2> =09at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEn= dPoint.java:124) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.doProduce(EatWhatYouKill.java:247) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.produce(EatWhatYouKill.java:140) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.run(EatWhatYouKill.java:131) [junit4] 2> =09at org.eclipse.jetty.util.thread.ReservedThreadExecutor= $ReservedThread.run(ReservedThreadExecutor.java:382) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:708) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:626) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: java.lang.IllegalArgumentException: userName is= NULL, empty or contains a '&' [junit4] 2> =09at org.apache.hadoop.security.authentication.util.AuthT= oken.checkForIllegalArgument(AuthToken.java:87) [junit4] 2> =09at org.apache.hadoop.security.authentication.util.AuthT= oken.(AuthToken.java:70) [junit4] 2> =09at org.apache.hadoop.security.authentication.server.Aut= henticationToken.(AuthenticationToken.java:58) [junit4] 2> =09at org.apache.solr.security.HttpParamDelegationTokenPlu= gin$HttpParamDelegationTokenAuthenticationHandler$HttpParamAuthenticationHa= ndler.authenticate(HttpParamDelegationTokenPlugin.java:213) [junit4] 2> =09at org.apache.hadoop.security.token.delegation.web.Dele= gationTokenAuthenticationHandler.authenticate(DelegationTokenAuthentication= Handler.java:348) [junit4] 2> =09at org.apache.solr.security.RequestContinuesRecorderAut= henticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandl= er.java:69) [junit4] 2> =09at org.apache.hadoop.security.authentication.server.Aut= henticationFilter.doFilter(AuthenticationFilter.java:518) [junit4] 2> =09at org.apache.solr.security.DelegationTokenKerberosFilt= er.doFilter(DelegationTokenKerberosFilter.java:134) [junit4] 2> =09at org.apache.solr.security.HttpParamDelegationTokenPlu= gin$HttpParamToRequestFilter.doFilter(HttpParamDelegationTokenPlugin.java:2= 45) [junit4] 2> =09at org.apache.solr.security.KerberosPlugin.doAuthentica= te(KerberosPlugin.java:243) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.authentic= ateRequest(SolrDispatchFilter.java:449) [junit4] 2> =09... 31 more [junit4] 2> 1159153 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNullProxyUser-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyNullProxyUser [junit4] 2> 1159178 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigHosts-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyNoConfigHosts [junit4] 2> 1159186 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigHosts-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponsePro= cessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DnoHosts&= p=3DnoHosts&t=3Dtest&e=3D1517523292922&s=3DViQD5c7BGjSeo3/Hf8Mu0IAvmkw=3D";= Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2561 22:14:52 GMT; Ht= tpOnly". Invalid 'expires' attribute: ., 01- . .-2561 22:14:52 GMT [junit4] 2> 1159186 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigHosts-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyNoConfigHosts [junit4] 2> 1159217 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyWrongHost-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 ###S= tarting testProxyWrongHost [junit4] 2> 1159263 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyWrongHost-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponseProcess= Cookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DwrongHost&p= =3DwrongHost&t=3Dtest&e=3D1517523292959&s=3DlPXMbDtXZNNDWGSJ+xngyt44Ac8=3D"= ; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2561 22:14:52 GMT; H= ttpOnly". Invalid 'expires' attribute: ., 01- . .-2561 22:14:52 GMT [junit4] 2> 1159264 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyWrongHost-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 ###E= nding testProxyWrongHost [junit4] 2> 1159307 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigGroups-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4= ###Starting testProxyNoConfigGroups [junit4] 2> 1159309 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigGroups-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponsePr= ocessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DnoGroup= s&p=3DnoGroups&t=3Dtest&e=3D1517523293048&s=3DRaz3o+0VV6+lLE7/O1DGIHoRVxg= =3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2561 22:14:53 GM= T; HttpOnly". Invalid 'expires' attribute: ., 01- . .-2561 22:14:53 GMT [junit4] 2> 1159309 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigGroups-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4= ###Ending testProxyNoConfigGroups [junit4] 2> 1159337 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidRemote-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyInvalidRemote [junit4] 2> 1159343 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidRemote-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponsePro= cessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DlocalHos= tAnyGroup&p=3DlocalHostAnyGroup&t=3Dtest&e=3D1517523293078&s=3D448Iw+VI2dBT= k5ZDV7R2R7L9Ago=3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2= 561 22:14:53 GMT; HttpOnly". Invalid 'expires' attribute: ., 01- . .-2561 2= 2:14:53 GMT [junit4] 2> 1159344 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidRemote-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyInvalidRemote [junit4] 2> 1159370 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateAnyHostAnyUser-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTe= stCaseJ4 ###Starting testProxyValidateAnyHostAnyUser [junit4] 2> 1159404 INFO (qtp1510026401-12316) [n:127.0.0.1:48706_sol= r ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with pa= rams doAs=3Dbar&action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQueue= =3Dtrue [junit4] 2> 1159404 INFO (qtp1510026401-12316) [n:127.0.0.1:48706_sol= r ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections= params=3D{doAs=3Dbar&action=3DLIST&wt=3Djavabin&version=3D2} status=3D0 QT= ime=3D0 [junit4] 2> 1159405 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateAnyHostAnyUser-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.Re= sponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u= =3DanyHostAnyUser&p=3DanyHostAnyUser&t=3Dtest&e=3D1517523293140&s=3DZa1FbHE= Fetrx/WMwa4o0iz+wDks=3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- = . .-2561 22:14:53 GMT; HttpOnly". Invalid 'expires' attribute: ., 01- . .-2= 561 22:14:53 GMT [junit4] 2> 1159405 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateAnyHostAnyUser-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTe= stCaseJ4 ###Ending testProxyValidateAnyHostAnyUser [junit4] 2> 1159491 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateGroup-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyValidateGroup [junit4] 2> 1159558 INFO (qtp1510026401-12314) [n:127.0.0.1:48706_sol= r ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with pa= rams doAs=3Djenkins&action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQue= ue=3Dtrue [junit4] 2> 1159559 INFO (qtp1510026401-12314) [n:127.0.0.1:48706_sol= r ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections= params=3D{doAs=3Djenkins&action=3DLIST&wt=3Djavabin&version=3D2} status=3D= 0 QTime=3D0 [junit4] 2> 1159559 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateGroup-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponsePro= cessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DanyHostU= sersGroup&p=3DanyHostUsersGroup&t=3Dtest&e=3D1517523293233&s=3DTYDD3Dx5UkA/= PYRga0k2i1kv3JQ=3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2= 561 22:14:53 GMT; HttpOnly". Invalid 'expires' attribute: ., 01- . .-2561 2= 2:14:53 GMT [junit4] 2> 1159559 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateGroup-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyValidateGroup [junit4] 2> 1159622 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateHost-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 #= ##Starting testProxyValidateHost [junit4] 2> 1159627 INFO (qtp1510026401-12316) [n:127.0.0.1:48706_sol= r ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with pa= rams doAs=3Dbar&action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQueue= =3Dtrue [junit4] 2> 1159627 INFO (qtp1510026401-12316) [n:127.0.0.1:48706_sol= r ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections= params=3D{doAs=3Dbar&action=3DLIST&wt=3Djavabin&version=3D2} status=3D0 QT= ime=3D0 [junit4] 2> 1159628 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateHost-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponseProc= essCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DlocalHost= AnyGroup&p=3DlocalHostAnyGroup&t=3Dtest&e=3D1517523293368&s=3DAZCvXEhqDJbuo= c97v+lAmECmmWg=3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-25= 61 22:14:53 GMT; HttpOnly". Invalid 'expires' attribute: ., 01- . .-2561 22= :14:53 GMT [junit4] 2> 1159628 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateHost-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 #= ##Ending testProxyValidateHost [junit4] 2> 1159669 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidGroup-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 #= ##Starting testProxyInvalidGroup [junit4] 2> 1159775 WARN (qtp1510026401-12314) [n:127.0.0.1:48706_sol= r ] o.a.h.s.ShellBasedUnixGroupsMapping got exception trying to get grou= ps for user bar: id: invalid user name: "bar" [junit4] 2> id: invalid user name: "bar" [junit4] 2>=20 [junit4] 2> 1159777 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidGroup-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponseProc= essCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DbogusGrou= p&p=3DbogusGroup&t=3Dtest&e=3D1517523293410&s=3DOii8J93CSzyB5ps9pGIw5t4dKNI= =3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2561 22:14:53 GM= T; HttpOnly". Invalid 'expires' attribute: ., 01- . .-2561 22:14:53 GMT [junit4] 2> 1159778 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidGroup-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 #= ##Ending testProxyInvalidGroup [junit4] 2> 1159875 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 ###Start= ing testForwarding [junit4] 2> 1159880 INFO (zkConnectionManagerCallback-2005-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1161111 INFO (zkConnectionManagerCallback-2008-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1161114 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1161116 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.s.c.s.i.ZkClientClusterSt= ateProvider Cluster at 127.0.0.1:58506/solr ready [junit4] 2> 1161118 INFO (qtp1510026401-12314) [n:127.0.0.1:48706_sol= r ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with = params pullReplicas=3D0&replicationFactor=3D1&collection.configName=3Dconf1= &maxShardsPerNode=3D1&name=3DforwardingCollection&nrtReplicas=3D1&action=3D= CREATE&numShards=3D1&tlogReplicas=3D0&wt=3Djavabin&version=3D2 and sendToOC= PQueue=3Dtrue [junit4] 2> 1161132 INFO (OverseerThreadFactory-2966-thread-1-process= ing-n:127.0.0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.a.c.Create= CollectionCmd Create collection forwardingCollection [junit4] 2> 1161267 INFO (OverseerStateUpdate-72123289957826565-127.0= .0.1:59765_solr-n_0000000000) [n:127.0.0.1:59765_solr ] o.a.s.c.o.SliceM= utator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"forwardingCollection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"forwardingCollection_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:59765/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1161480 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&c= oreNodeName=3Dcore_node2&collection.configName=3Dconf1&newCollection=3Dtrue= &name=3DforwardingCollection_shard1_replica_n1&action=3DCREATE&numShards=3D= 1&collection=3DforwardingCollection&shard=3Dshard1&wt=3Djavabin&version=3D2= &replicaType=3DNRT [junit4] 2> 1161480 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for= 2147483647 transient cores [junit4] 2> 1161600 INFO (zkCallback-1993-thread-1-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/forwardingCollection/state.json] for collection [forwardin= gCollection] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1161600 INFO (zkCallback-1993-thread-2-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/forwardingCollection/state.json] for collection [forwardin= gCollection] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1162570 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1162603 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.s.IndexSchema [forwardingCollection_shard1_replica_n1] = Schema name=3Dtest [junit4] 2> 1162924 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid fiel= d id [junit4] 2> 1163033 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'forwardingCollection= _shard1_replica_n1' using configuration from collection forwardingCollectio= n, trusted=3Dtrue [junit4] 2> 1163046 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59765.solr= .core.forwardingCollection.shard1.replica_n1' (registry 'solr.core.forwardi= ngCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver= .JmxMBeanServer@1b3ca7b [junit4] 2> 1163047 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1163048 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.SolrCore [[forwardingCollection_shard1_replica_n1] ] = Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-= Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecur= eImpersonation_9135ED1CEA3F5160-001/tempDir-001/node1/forwardingCollection_= shard1_replica_n1], dataDir=3D[/export/home/jenkins/workspace/Lucene-Solr-m= aster-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWit= hSecureImpersonation_9135ED1CEA3F5160-001/tempDir-001/node1/./forwardingCol= lection_shard1_replica_n1/data/] [junit4] 2> 1163055 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class or= g.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce= =3D36, maxMergeAtOnceExplicit=3D27, maxMergedSegmentMB=3D36.3662109375, flo= orSegmentMB=3D1.49609375, forceMergeDeletesPctAllowed=3D26.031912151890648,= segmentsPerTier=3D14.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSR= atio=3D0.7928370962293599 [junit4] 2> 1163180 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apa= che.solr.update.UpdateLog [junit4] 2> 1163181 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultS= yncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuc= kets=3D65536 [junit4] 2> 1163182 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1163182 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1163183 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class or= g.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize= =3D1000, mergeFactor=3D27, maxMergeSize=3D9223372036854775807, maxMergeSize= ForForcedMerge=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMer= geDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D0.3630109340176154] [junit4] 2> 1163184 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3066f72b[forwardi= ngCollection_shard1_replica_n1] main] [junit4] 2> 1163189 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1163197 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=3Ddirect,classname= =3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLength=3D3} [junit4] 2> 1163217 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000= ms. [junit4] 2> 1163217 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or rece= nt updates, using new clock 1591200759895031808 [junit4] 2> 1163219 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1163223 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 1163225 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 1163225 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFiel= ds [junit4] 2> 1163228 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 1163231 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 1163232 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to c= ontinue. [junit4] 2> 1163232 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - = try and sync [junit4] 2> 1163232 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59765/= solr/forwardingCollection_shard1_replica_n1/ [junit4] 2> 1163232 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1163232 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:59765/solr/forwardingCo= llection_shard1_replica_n1/ has no replicas [junit4] 2> 1163232 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas partici= pating in election, clear LIR [junit4] 2> 1163232 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 1163235 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 1163237 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http:= //127.0.0.1:59765/solr/forwardingCollection_shard1_replica_n1/ shard1 [junit4] 2> 1163238 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 1163239 INFO (searcherExecutor-2971-thread-1-processing-n= :127.0.0.1:59765_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:59765_solr c:forwardingColle= ction s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s= .c.SolrCore [forwardingCollection_shard1_replica_n1] Registered new searche= r Searcher@3066f72b[forwardingCollection_shard1_replica_n1] main{ExitableDi= rectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1163342 INFO (zkCallback-1993-thread-2-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/forwardingCollection/state.json] for collection [forwardin= gCollection] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1163342 INFO (zkCallback-1993-thread-1-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/forwardingCollection/state.json] for collection [forwardin= gCollection] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1163389 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1163392 INFO (qtp1690636625-12302) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/core= s params=3D{qt=3D/admin/cores&coreNodeName=3Dcore_node2&collection.configNa= me=3Dconf1&newCollection=3Dtrue&name=3DforwardingCollection_shard1_replica_= n1&action=3DCREATE&numShards=3D1&collection=3DforwardingCollection&shard=3D= shard1&wt=3Djavabin&version=3D2&replicaType=3DNRT} status=3D0 QTime=3D1913 [junit4] 2> 1163392 WARN (httpShardExecutor-2962-thread-1-processing-= n:127.0.0.1:59765_solr [http:////127.0.0.1:59765//solr] http:////127.0.0.1:= 59765//solr) [n:127.0.0.1:59765_solr ] o.a.h.c.p.ResponseProcessCookies = Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3D$&p=3D$&t=3Dtest&e= =3D1517523295220&s=3D39NWGwUeGGVz6c9DlyMPifxhoso=3D"; Path=3D/; Domain=3D12= 7.0.0.1; Expires=3D ., 01- . .-2561 22:14:55 GMT; HttpOnly". Invalid 'expi= res' attribute: ., 01- . .-2561 22:14:55 GMT [junit4] 2> 1163396 INFO (qtp1510026401-12314) [n:127.0.0.1:48706_sol= r ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active fo= r at most 30 seconds. Check all shard replicas [junit4] 2> 1163493 INFO (zkCallback-1993-thread-1-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/forwardingCollection/state.json] for collection [forwardin= gCollection] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1163493 INFO (zkCallback-1993-thread-2-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/forwardingCollection/state.json] for collection [forwardin= gCollection] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1164401 INFO (qtp1510026401-12314) [n:127.0.0.1:48706_sol= r ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections= params=3D{pullReplicas=3D0&replicationFactor=3D1&collection.configName=3Dc= onf1&maxShardsPerNode=3D1&name=3DforwardingCollection&nrtReplicas=3D1&actio= n=3DCREATE&numShards=3D1&tlogReplicas=3D0&wt=3Djavabin&version=3D2} status= =3D0 QTime=3D3283 [junit4] 2> 1164401 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponseProcessCook= ies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3Duser&p=3Duser&t= =3Dtest&e=3D1517523294859&s=3DVmWp8nb92cu3fLshGMm6ftD3ex0=3D"; Path=3D/; Do= main=3D127.0.0.1; Expires=3D ., 01- . .-2561 22:14:54 GMT; HttpOnly". Inva= lid 'expires' attribute: ., 01- . .-2561 22:14:54 GMT [junit4] 2> 1164402 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.s.c.AbstractDistribZkTest= Base Wait for recoveries to finish - collection: forwardingCollection failO= nTimeout:true timeout (sec):100 [junit4] 2> 1164405 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.s.c.AbstractDistribZkTest= Base Recoveries finished - collection: forwardingCollection [junit4] 2> 1164409 INFO (qtp1510026401-12314) [n:127.0.0.1:48706_sol= r ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for= 2147483647 transient cores [junit4] 2> 1164417 INFO (qtp1690636625-12303) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.S.Request [forwardingCollection_shard1_replica_n1] w= ebapp=3D/solr path=3D/select params=3D{q=3D*:*&wt=3Djavabin&version=3D2} hi= ts=3D0 status=3D0 QTime=3D0 [junit4] 2> 1164417 WARN (qtp1510026401-12314) [n:127.0.0.1:48706_sol= r ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:= hadoop.auth=3D"u=3D$&p=3D$&t=3Dtest&e=3D1517523298152&s=3D9s832qHAqXWs/XqA= s+703gS4FJQ=3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2561 = 22:14:58 GMT; HttpOnly". Invalid 'expires' attribute: ., 01- . .-2561 22:14= :58 GMT [junit4] 2> 1164418 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponseProcessCook= ies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3Duser&p=3Duser&t= =3Dtest&e=3D1517523298149&s=3D2NJFAPBa1pBdTYySR1N1zvpDJ2I=3D"; Path=3D/; Do= main=3D127.0.0.1; Expires=3D ., 01- . .-2561 22:14:58 GMT; HttpOnly". Inva= lid 'expires' attribute: ., 01- . .-2561 22:14:58 GMT [junit4] 2> 1164418 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponseProcessCook= ies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3D$&p=3D$&t=3Dtest= &e=3D1517523298152&s=3D9s832qHAqXWs/XqAs+703gS4FJQ=3D"; Path=3D/; Domain=3D= 127.0.0.1; Expires=3D ., 01- . .-2561 22:14:58 GMT; HttpOnly". Invalid 'ex= pires' attribute: ., 01- . .-2561 22:14:58 GMT [junit4] 2> 1164427 INFO (qtp1690636625-12303) [n:127.0.0.1:59765_sol= r c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard= 1_replica_n1] o.a.s.c.S.Request [forwardingCollection_shard1_replica_n1] w= ebapp=3D/solr path=3D/select params=3D{q=3D*:*&wt=3Djavabin&version=3D2} hi= ts=3D0 status=3D0 QTime=3D0 [junit4] 2> 1164428 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponseProcessCook= ies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3Duser&p=3Duser&t= =3Dtest&e=3D1517523298167&s=3DOXmbY54MVsIIFfPSuWifvjpWxZ8=3D"; Path=3D/; Do= main=3D127.0.0.1; Expires=3D ., 01- . .-2561 22:14:58 GMT; HttpOnly". Inva= lid 'expires' attribute: ., 01- . .-2561 22:14:58 GMT [junit4] 2> 1164428 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 ###Endin= g testForwarding [junit4] 2> 1164590 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyUnknownRemote-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyUnknownRemote [junit4] 2> 1176197 WARN (jetty-launcher-1977-thread-1-SendThread(127= .0.0.1:58506)) [n:127.0.0.1:59765_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 17250ms for sessionid 0x1003bc005= f80007 [junit4] 2> 1176198 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessio= nid 0x1003bc005f80007, likely client has closed socket [junit4] 2> 1176199 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyUnknownRemote-seed#[9135ED1CEA3F5160]) [ ] o.a.h.c.p.ResponsePro= cessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DlocalHos= tAnyGroup&p=3DlocalHostAnyGroup&t=3Dtest&e=3D1517523298338&s=3DH2SCqzomrgol= YODZTJzCp3MUzMs=3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D ., 01- . .-2= 561 22:14:58 GMT; HttpOnly". Invalid 'expires' attribute: ., 01- . .-2561 2= 2:14:58 GMT [junit4] 2> 1176199 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyUnknownRemote-seed#[9135ED1CEA3F5160]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyUnknownRemote [junit4] 2> 1176198 WARN (jetty-launcher-1977-thread-2-SendThread(127= .0.0.1:58506)) [n:127.0.0.1:48706_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 17252ms for sessionid 0x1003bc005= f80008 [junit4] 2> 1176203 INFO (OverseerCollectionConfigSetProcessor-721232= 89957826565-127.0.0.1:59765_solr-n_0000000000) [n:127.0.0.1:59765_solr ]= o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-wor= k/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKe= eper [junit4] 2> 1176204 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessio= nid 0x1003bc005f80008, likely client has closed socket [junit4] 2> 1176305 WARN (zkConnectionManagerCallback-2008-thread-1) = [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.Con= nectionManager@235f94e name: ZooKeeperConnection Watcher:127.0.0.1:58506/so= lr got event WatchedEvent state:Disconnected type:None path:null path: null= type: None [junit4] 2> 1176305 WARN (zkConnectionManagerCallback-2008-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 1176309 INFO (jetty-closer-1978-thread-1) [ ] o.e.j.s.= AbstractConnector Stopped ServerConnector@38712084{HTTP/1.1,[http/1.1]}{127= .0.0.1:0} [junit4] 2> 1176335 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.c.= CoreContainer Shutting down CoreContainer instance=3D1988954940 [junit4] 2> 1176336 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.node, tag=3D= null [junit4] 2> 1176337 INFO (jetty-closer-1978-thread-2) [ ] o.e.j.s.= AbstractConnector Stopped ServerConnector@7f83e19e{HTTP/1.1,[http/1.1]}{127= .0.0.1:0} [junit4] 2> 1176346 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@78ce18b4: rootName =3D solr_48706, domain =3D solr.node, service= url =3D null, agent id =3D null] for registry solr.node / com.codahale.met= rics.MetricRegistry@6bf6a8b9 [junit4] 2> 1176362 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jvm, tag=3Dn= ull [junit4] 2> 1176362 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@61f9287f: rootName =3D solr_48706, domain =3D solr.jvm, service = url =3D null, agent id =3D null] for registry solr.jvm / com.codahale.metri= cs.MetricRegistry@4c374267 [junit4] 2> 1176366 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag= =3Dnull [junit4] 2> 1176366 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@29607302: rootName =3D solr_48706, domain =3D solr.jetty, servic= e url =3D null, agent id =3D null] for registry solr.jetty / com.codahale.m= etrics.MetricRegistry@1db7b2e9 [junit4] 2> 1176369 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.c.= CoreContainer Shutting down CoreContainer instance=3D668678898 [junit4] 2> 1176369 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.node, tag=3D= null [junit4] 2> 1176369 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@48de1f35: rootName =3D solr_59765, domain =3D solr.node, service= url =3D null, agent id =3D null] for registry solr.node / com.codahale.met= rics.MetricRegistry@54f64cc [junit4] 2> 1176373 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jvm, tag=3Dn= ull [junit4] 2> 1176373 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@6a271906: rootName =3D solr_59765, domain =3D solr.jvm, service = url =3D null, agent id =3D null] for registry solr.jvm / com.codahale.metri= cs.MetricRegistry@4c374267 [junit4] 2> 1176376 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag= =3Dnull [junit4] 2> 1176377 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@2f47e850: rootName =3D solr_59765, domain =3D solr.jetty, servic= e url =3D null, agent id =3D null] for registry solr.jetty / com.codahale.m= etrics.MetricRegistry@1db7b2e9 [junit4] 2> 1176379 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.c.= ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:59765_s= olr [junit4] 2> 1176379 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.c.= ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:48706_s= olr [junit4] 2> 1176380 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag= =3Dnull [junit4] 2> 1176380 INFO (zkCallback-1993-thread-1-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 1176385 INFO (coreCloseExecutor-2976-thread-1) [n:127.0.0= .1:59765_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingColl= ection_shard1_replica_n1] o.a.s.c.SolrCore [forwardingCollection_shard1_rep= lica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@49be8b41 [junit4] 2> 1176385 INFO (coreCloseExecutor-2976-thread-1) [n:127.0.0= .1:59765_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingColl= ection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporter= s for registry=3Dsolr.core.forwardingCollection.shard1.replica_n1, tag=3D12= 37224257 [junit4] 2> 1176385 INFO (coreCloseExecutor-2976-thread-1) [n:127.0.0= .1:59765_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingColl= ection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.a= pache.solr.metrics.reporters.SolrJmxReporter@287339b4: rootName =3D solr_59= 765, domain =3D solr.core.forwardingCollection.shard1.replica_n1, service u= rl =3D null, agent id =3D null] for registry solr.core.forwardingCollection= .shard1.replica_n1 / com.codahale.metrics.MetricRegistry@5f6e7f37 [junit4] 2> 1176385 INFO (zkCallback-1995-thread-1-processing-n:127.0= .0.1:48706_solr) [n:127.0.0.1:48706_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 1176388 INFO (jetty-closer-1978-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag= =3Dnull [junit4] 2> 1176392 ERROR (Thread[Thread-5822,5,TGRP-TestSolrCloudWith= SecureImpersonation]) [n:127.0.0.1:48706_solr ] o.a.h.s.t.d.AbstractDele= gationTokenSecretManager ExpiredTokenRemover received java.lang.Interrupted= Exception: sleep interrupted [junit4] 2> 1176403 INFO (zkCallback-1993-thread-3-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/forwardingCollection/state.json] for collection [forwardin= gCollection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1176404 INFO (zkCallback-1993-thread-1-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 1176404 INFO (zkCallback-1993-thread-2-processing-n:127.0= .0.1:59765_solr) [n:127.0.0.1:59765_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/forwardingCollection/state.json] for collection [forwardin= gCollection] has occurred - updating... (live nodes size: [0]) [junit4] 2> 1176423 INFO (coreCloseExecutor-2976-thread-1) [n:127.0.0= .1:59765_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingColl= ection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporter= s for registry=3Dsolr.collection.forwardingCollection.shard1.leader, tag=3D= 1237224257 [junit4] 2> 1176436 INFO (jetty-closer-1978-thread-2) [ ] o.a.s.c.= Overseer Overseer (id=3D72123289957826565-127.0.0.1:59765_solr-n_0000000000= ) closing [junit4] 2> 1176436 INFO (OverseerStateUpdate-72123289957826565-127.0= .0.1:59765_solr-n_0000000000) [n:127.0.0.1:59765_solr ] o.a.s.c.Overseer= Overseer Loop exiting : 127.0.0.1:59765_solr [junit4] 2> 1176439 WARN (OverseerAutoScalingTriggerThread-7212328995= 7826565-127.0.0.1:59765_solr-n_0000000000) [n:127.0.0.1:59765_solr ] o.a= .s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are clos= ed, exiting. [junit4] 2> 1176444 ERROR (Thread[Thread-5824,5,TGRP-TestSolrCloudWith= SecureImpersonation]) [n:127.0.0.1:59765_solr ] o.a.h.s.t.d.AbstractDele= gationTokenSecretManager ExpiredTokenRemover received java.lang.Interrupted= Exception: sleep interrupted [junit4] 2> 1177232 INFO (jetty-closer-1978-thread-2) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@59353eae{/solr,null,= UNAVAILABLE} [junit4] 2> 1177233 INFO (jetty-closer-1978-thread-2) [ ] o.e.j.s.= session Stopped scavenging [junit4] 2> 1177414 INFO (jetty-closer-1978-thread-1) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@56895767{/solr,null,= UNAVAILABLE} [junit4] 2> 1177416 INFO (jetty-closer-1978-thread-1) [ ] o.e.j.s.= session Stopped scavenging [junit4] 2> 1177420 ERROR (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHa= ndler is not registered, so ZooKeeper server won't take any action on ERROR= or SHUTDOWN server state changes [junit4] 2> 1177422 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.c.ZkTestServer connecting to 1= 27.0.0.1:58506 58506 [junit4] 2> 1179083 INFO (Thread-5805) [ ] o.a.s.c.ZkTestServer co= nnecting to 127.0.0.1:58506 58506 [junit4] 2> 1179083 WARN (Thread-5805) [ ] o.a.s.c.ZkTestServer Wa= tch limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =093=09/solr/aliases.json [junit4] 2> =093=09/solr/clusterprops.json [junit4] 2> =092=09/solr/security.json [junit4] 2> =092=09/solr/collections/forwardingCollection/terms/shard1 [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =096=09/solr/collections/forwardingCollection/state.json [junit4] 2> =093=09/solr/clusterstate.json [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t/DK_1 [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t/DK_3 [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t/DK_2 [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t/DK_4 [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =093=09/solr/live_nodes [junit4] 2> =093=09/solr/collections [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot [junit4] 2>=20 [junit4] 2> 1179179 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[9135ED1CEA3F5160]-worker) [ ] o.a.s.SolrTestCaseJ4 ---------------= ---------------------------------------- Done waiting for tracked resources= to be released [junit4] 2> Feb 01, 2018 12:15:12 PM com.carrotsearch.randomizedtestin= g.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thre= ad(s). [junit4] 2> Feb 01, 2018 12:15:33 PM com.carrotsearch.randomizedtestin= g.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 2 threads leaked from SUITE scope at org.apache.so= lr.cloud.TestSolrCloudWithSecureImpersonation:=20 [junit4] 2> 1) Thread[id=3D12357, name=3Djetty-launcher-1977-thread= -2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] 2> at java.util.concurrent.CountDownLatch.await(Count= DownLatch.java:277) [junit4] 2> at org.apache.curator.CuratorZookeeperClient.inter= nalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(Retr= yLoop.java:105) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:279) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:41) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue.readValue(SharedValue.java:244) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue.access$100(SharedValue.java:44) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue$1.process(SharedValue.java:61) [junit4] 2> at org.apache.curator.framework.imps.NamespaceWatc= her.process(NamespaceWatcher.java:67) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.pro= cessEvent(ClientCnxn.java:531) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:506) [junit4] 2> 2) Thread[id=3D12352, name=3Djetty-launcher-1977-thread= -1-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] 2> at java.util.concurrent.CountDownLatch.await(Count= DownLatch.java:277) [junit4] 2> at org.apache.curator.CuratorZookeeperClient.inter= nalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(Retr= yLoop.java:105) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:279) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:41) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue.readValue(SharedValue.java:244) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue.access$100(SharedValue.java:44) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue$1.process(SharedValue.java:61) [junit4] 2> at org.apache.curator.framework.imps.NamespaceWatc= her.process(NamespaceWatcher.java:67) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.pro= cessEvent(ClientCnxn.java:531) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:506) [junit4] 2> Feb 01, 2018 12:15:33 PM com.carrotsearch.randomizedtestin= g.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=3D12357, name=3Djetty-launcher-1977-thread= -2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] 2> 2) Thread[id=3D12352, name=3Djetty-launcher-1977-thread= -1-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] 2> 1199362 ERROR (jetty-launcher-1977-thread-1-EventThread) [= n:127.0.0.1:59765_solr ] o.a.c.f.i.CuratorFrameworkImpl Watcher exceptio= n [junit4] 2> java.lang.InterruptedException [junit4] 2> =09at java.util.concurrent.locks.AbstractQueuedSynchronize= r.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039) [junit4] 2> =09at java.util.concurrent.locks.AbstractQueuedSynchronize= r.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] 2> =09at java.util.concurrent.CountDownLatch.await(CountDownL= atch.java:277) [junit4] 2> =09at org.apache.curator.CuratorZookeeperClient.internalBl= ockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] 2> =09at org.apache.curator.RetryLoop.callWithRetry(RetryLoop= .java:105) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .pathInForeground(GetDataBuilderImpl.java:288) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .forPath(GetDataBuilderImpl.java:279) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .forPath(GetDataBuilderImpl.java:41) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue.readValue(SharedValue.java:244) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue.access$100(SharedValue.java:44) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue$1.process(SharedValue.java:61) [junit4] 2> =09at org.apache.curator.framework.imps.NamespaceWatcher.p= rocess(NamespaceWatcher.java:67) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.processE= vent(ClientCnxn.java:531) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:506) [junit4] 2> 1199362 ERROR (jetty-launcher-1977-thread-2-EventThread) [= n:127.0.0.1:48706_solr ] o.a.c.f.i.CuratorFrameworkImpl Watcher exceptio= n [junit4] 2> java.lang.InterruptedException [junit4] 2> =09at java.util.concurrent.locks.AbstractQueuedSynchronize= r.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039) [junit4] 2> =09at java.util.concurrent.locks.AbstractQueuedSynchronize= r.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] 2> =09at java.util.concurrent.CountDownLatch.await(CountDownL= atch.java:277) [junit4] 2> =09at org.apache.curator.CuratorZookeeperClient.internalBl= ockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] 2> =09at org.apache.curator.RetryLoop.callWithRetry(RetryLoop= .java:105) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .pathInForeground(GetDataBuilderImpl.java:288) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .forPath(GetDataBuilderImpl.java:279) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .forPath(GetDataBuilderImpl.java:41) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue.readValue(SharedValue.java:244) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue.access$100(SharedValue.java:44) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue$1.process(SharedValue.java:61) [junit4] 2> =09at org.apache.curator.framework.imps.NamespaceWatcher.p= rocess(NamespaceWatcher.java:67) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.processE= vent(ClientCnxn.java:531) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:506) [junit4] 2> Feb 01, 2018 12:15:33 PM com.carrotsearch.randomizedtestin= g.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=3DCheapBastard, sim=3DAsserti= ng(org.apache.lucene.search.similarities.AssertingSimilarity@6715ea67), loc= ale=3Dth-TH, timezone=3DIran [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bi= t)/cpus=3D3,threads=3D1,free=3D118689056,total=3D516947968 [junit4] 2> NOTE: All tests run in this JVM: [TestSolrConfigHandlerCon= current, CollectionStateFormat2Test, TestCSVLoader, PeerSyncTest, TestDocTe= rmOrds, SpellPossibilityIteratorTest, LargeFieldTest, TestConfigSetsAPI, Te= stMacroExpander, CheckHdfsIndexTest, NodeLostTriggerTest, ReplaceNodeNoTarg= etTest, JavabinLoaderTest, UpdateLogTest, TestSizeLimitedDistributedMap, So= lrXmlInZkTest, TestConfigOverlay, TestExportWriter, TestMiniSolrCloudCluste= rSSL, TestHashQParserPlugin, ReturnFieldsTest, CoreAdminHandlerTest, TestRe= coveryHdfs, PropertiesRequestHandlerTest, ChaosMonkeyNothingIsSafeTest, Tes= tSortByMinMaxFunction, TestSolrQueryParser, SolrShardReporterTest, UtilsToo= lTest, TestAnalyzeInfixSuggestions, TestSolrCLIRunExample, TestCrossCoreJoi= n, HdfsBasicDistributedZk2Test, TestStressReorder, TestSolrDeletionPolicy2,= MultiThreadedOCPTest, DocValuesTest, BooleanFieldTest, TestIntervalFacetin= g, AnalysisErrorHandlingTest, LoggingHandlerTest, LeaderFailureAfterFreshSt= artTest, TestManagedResourceStorage, RecoveryAfterSoftCommitTest, TestJetty= SolrRunner, TestDownShardTolerantSearch, TemplateUpdateProcessorTest, DocVa= luesMultiTest, RegexBoostProcessorTest, TestDeleteCollectionOnDownNodes, Te= stClusterStateMutator, TestUninvertingReader, TestFieldCacheSortRandom, Dis= tributedSpellCheckComponentTest, TestMissingGroups, DebugComponentTest, Del= eteLastCustomShardedReplicaTest, LegacyCloudClusterPropTest, TestRequestSta= tusCollectionAPI, TestRuleBasedAuthorizationPlugin, BasicAuthStandaloneTest= , DistributedFacetPivotLargeTest, TestBlobHandler, ConjunctionSolrSpellChec= kerTest, BaseCdcrDistributedZkTest, TestSearcherReuse, TestConfigSetPropert= ies, MissingSegmentRecoveryTest, XsltUpdateRequestHandlerTest, CSVRequestHa= ndlerTest, TestLuceneMatchVersion, TestSolrCloudWithSecureImpersonation] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestSolrCloud= WithSecureImpersonation -Dtests.seed=3D9135ED1CEA3F5160 -Dtests.slow=3Dtrue= -Dtests.locale=3Dth-TH -Dtests.timezone=3DIran -Dtests.asserts=3Dtrue -Dte= sts.file.encoding=3DUS-ASCII [junit4] ERROR 0.00s J1 | TestSolrCloudWithSecureImpersonation (suite)= <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLea= kError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolr= CloudWithSecureImpersonation:=20 [junit4] > 1) Thread[id=3D12357, name=3Djetty-launcher-1977-thread= -2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] > at java.util.concurrent.CountDownLatch.await(Count= DownLatch.java:277) [junit4] > at org.apache.curator.CuratorZookeeperClient.inter= nalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] > at org.apache.curator.RetryLoop.callWithRetry(Retr= yLoop.java:105) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:279) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:41) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue.readValue(SharedValue.java:244) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue.access$100(SharedValue.java:44) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue$1.process(SharedValue.java:61) [junit4] > at org.apache.curator.framework.imps.NamespaceWatc= her.process(NamespaceWatcher.java:67) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.pro= cessEvent(ClientCnxn.java:531) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:506) [junit4] > 2) Thread[id=3D12352, name=3Djetty-launcher-1977-thread= -1-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] > at java.util.concurrent.CountDownLatch.await(Count= DownLatch.java:277) [junit4] > at org.apache.curator.CuratorZookeeperClient.inter= nalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] > at org.apache.curator.RetryLoop.callWithRetry(Retr= yLoop.java:105) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:279) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:41) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue.readValue(SharedValue.java:244) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue.access$100(SharedValue.java:44) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue$1.process(SharedValue.java:61) [junit4] > at org.apache.curator.framework.imps.NamespaceWatc= her.process(NamespaceWatcher.java:67) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.pro= cessEvent(ClientCnxn.java:531) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:506) [junit4] > =09at __randomizedtesting.SeedInfo.seed([9135ED1CEA3F5160]= :0) [junit4] Completed [145/774 (1!)] on J1 in 41.71s, 12 tests, 1 error <<<= FAILURES! [...truncated 50272 lines...] ------=_Part_46_484114832.1517492997281 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_46_484114832.1517492997281--