Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 42069200D36 for ; Mon, 6 Nov 2017 11:37:21 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id 40566160BEC; Mon, 6 Nov 2017 10:37:21 +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 B1C821609E0 for ; Mon, 6 Nov 2017 11:37:18 +0100 (CET) Received: (qmail 59267 invoked by uid 500); 6 Nov 2017 10:37: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 59257 invoked by uid 99); 6 Nov 2017 10:37:17 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd3-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 06 Nov 2017 10:37:17 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd3-us-west.apache.org (ASF Mail Server at spamd3-us-west.apache.org) with ESMTP id 310611805D0 for ; Mon, 6 Nov 2017 10:37:16 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-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-eu.apache.org ([10.40.0.8]) by localhost (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id 0Qto6RRQBxnt for ; Mon, 6 Nov 2017 10:37:00 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [88.99.242.108]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id 1AC5061536 for ; Mon, 6 Nov 2017 10:36:42 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id AC81010807D0 for ; Mon, 6 Nov 2017 10:36:41 +0000 (UTC) Date: Mon, 6 Nov 2017 10:35:13 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1825520863.387.1509964601707.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build # 1516 - Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_386_1261961011.1509964601675" X-Jenkins-Job: Lucene-Solr-master-Solaris X-Jenkins-Result: UNSTABLE archived-at: Mon, 06 Nov 2017 10:37:21 -0000 ------=_Part_386_1261961011.1509964601675 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1516/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC 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=3D28961, name=3Djetty-launcher-5582-= 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:530) at org.apache.zookeeper.C= lientCnxn$EventThread.run(ClientCnxn.java:505) 2) Thread[id=3D28951, nam= e=3Djetty-launcher-5582-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:530) = at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from S= UITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:= =20 1) Thread[id=3D28961, name=3Djetty-launcher-5582-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:530) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:= 505) 2) Thread[id=3D28951, name=3Djetty-launcher-5582-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:530) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:= 505) =09at __randomizedtesting.SeedInfo.seed([1F9CA8CD46E821A4]:0) Build Log: [...truncated 13397 lines...] [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithSecureImpersonati= on [junit4] 2> 3122086 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-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_1F9CA8CD46E821A4-001/init-core-data-001 [junit4] 2> 3122086 WARN (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSe= archers: numOpens=3D4 numCloses=3D4 [junit4] 2> 3122087 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFiel= ds (NUMERIC_POINTS_SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 3122088 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-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> 3122148 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-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_1F9CA8CD46E821A4-001/tempDir-001 [junit4] 2> 3122149 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TES= T SERVER [junit4] 2> 3122149 INFO (Thread-7613) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3122149 INFO (Thread-7613) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 3122151 ERROR (Thread-7613) [ ] 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> 3122250 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-worker) [ ] o.a.s.c.ZkTestServer start zk server= on port:36781 [junit4] 2> 3122261 INFO (jetty-launcher-5582-thread-1) [ ] o.e.j.= s.Server jetty-9.3.20.v20170531 [junit4] 2> 3122261 INFO (jetty-launcher-5582-thread-2) [ ] o.e.j.= s.Server jetty-9.3.20.v20170531 [junit4] 2> 3122264 INFO (jetty-launcher-5582-thread-2) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12cc8058{/solr,nul= l,AVAILABLE} [junit4] 2> 3122264 INFO (jetty-launcher-5582-thread-1) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7da80e0a{/solr,nul= l,AVAILABLE} [junit4] 2> 3122264 INFO (jetty-launcher-5582-thread-2) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@12f47cb6{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:49771} [junit4] 2> 3122264 INFO (jetty-launcher-5582-thread-1) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@73e549cd{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:46679} [junit4] 2> 3122264 INFO (jetty-launcher-5582-thread-2) [ ] o.e.j.= s.Server Started @3125062ms [junit4] 2> 3122264 INFO (jetty-launcher-5582-thread-1) [ ] o.e.j.= s.Server Started @3125062ms [junit4] 2> 3122264 INFO (jetty-launcher-5582-thread-2) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D49= 771} [junit4] 2> 3122264 INFO (jetty-launcher-5582-thread-1) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D46= 679} [junit4] 2> 3122264 ERROR (jetty-launcher-5582-thread-2) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 3122265 INFO (jetty-launcher-5582-thread-2) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 8.0.0 [junit4] 2> 3122265 INFO (jetty-launcher-5582-thread-2) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3122265 INFO (jetty-launcher-5582-thread-2) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3122265 INFO (jetty-launcher-5582-thread-2) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-06T09:58:48.635Z [junit4] 2> 3122264 ERROR (jetty-launcher-5582-thread-1) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 3122265 INFO (jetty-launcher-5582-thread-1) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 8.0.0 [junit4] 2> 3122265 INFO (jetty-launcher-5582-thread-1) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3122265 INFO (jetty-launcher-5582-thread-1) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3122265 INFO (jetty-launcher-5582-thread-1) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-06T09:58:48.635Z [junit4] 2> 3122270 INFO (jetty-launcher-5582-thread-2) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3122270 INFO (jetty-launcher-5582-thread-1) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3122279 INFO (jetty-launcher-5582-thread-2) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:36781/solr [junit4] 2> 3122279 INFO (jetty-launcher-5582-thread-1) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:36781/solr [junit4] 2> 3122433 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 3122435 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader= 127.0.0.1:49771_solr [junit4] 2> 3122436 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.s.c.Overseer Overseer (id=3D98956891161886725-127.0.0.1= :49771_solr-n_0000000000) starting [junit4] 2> 3122437 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 3122438 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:46679_solr [junit4] 2> 3122444 INFO (zkCallback-5593-thread-1-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3122445 INFO (zkCallback-5594-thread-1-processing-n:127.0= .0.1:46679_solr) [n:127.0.0.1:46679_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3122451 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:49771_solr [junit4] 2> 3122455 INFO (zkCallback-5593-thread-1-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3122460 INFO (zkCallback-5594-thread-1-processing-n:127.0= .0.1:46679_solr) [n:127.0.0.1:46679_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3122521 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: o= rg.apache.solr.security.HttpParamDelegationTokenPlugin [junit4] 2> 3122521 WARN (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_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> 3122522 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_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> 3122523 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 3122525 INFO (jetty-launcher-5582-thread-1-EventThread) [= n:127.0.0.1:46679_solr ] o.a.c.f.s.ConnectionStateManager State change: = CONNECTED [junit4] 2> 3122566 WARN (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_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> 3122613 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: o= rg.apache.solr.security.HttpParamDelegationTokenPlugin [junit4] 2> 3122613 WARN (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_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> 3122613 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_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> 3122619 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 3122621 INFO (jetty-launcher-5582-thread-2-EventThread) [= n:127.0.0.1:49771_solr ] o.a.c.f.s.ConnectionStateManager State change: = CONNECTED [junit4] 2> 3122635 WARN (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_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> 3122636 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46679.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@6b326590 [junit4] 2> 3122644 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46679.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@6b326590 [junit4] 2> 3122645 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46679.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@6b326590 [junit4] 2> 3122646 INFO (jetty-launcher-5582-thread-1) [n:127.0.0.1:= 46679_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_1F9= CA8CD46E821A4-001/tempDir-001/node1/. [junit4] 2> 3122689 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49771.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@6b326590 [junit4] 2> 3122697 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49771.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@6b326590 [junit4] 2> 3122698 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49771.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@6b326590 [junit4] 2> 3122699 INFO (jetty-launcher-5582-thread-2) [n:127.0.0.1:= 49771_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_1F9= CA8CD46E821A4-001/tempDir-001/node2/. [junit4] 2> 3122741 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNullProxyUser-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyNullProxyUser [junit4] 2> 3122744 INFO (qtp295414161-28905) [ ] o.a.s.s.SolrDisp= atchFilter 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:451) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:347) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:326) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1759) [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:1759) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:582) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:224) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1180) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:512) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:185) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1112) [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:493) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:134) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 34) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:320) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:251) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:283) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:108) [junit4] 2> =09at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(Sel= ectChannelEndPoint.java:93) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:671) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:589) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3122745 WARN (qtp295414161-28905) [ ] o.e.j.s.ServletH= andler /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:457) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:347) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:326) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1759) [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:1759) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:582) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:224) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1180) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:512) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:185) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1112) [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:493) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:134) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 34) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:320) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:251) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:283) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:108) [junit4] 2> =09at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(Sel= ectChannelEndPoint.java:93) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:671) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:589) [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:451) [junit4] 2> =09... 26 more [junit4] 2> 3122746 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNullProxyUser-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyNullProxyUser [junit4] 2> 3122767 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigHosts-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyNoConfigHosts [junit4] 2> 3122771 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigHosts-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyNoConfigHosts [junit4] 2> 3122793 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigGroups-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4= ###Starting testProxyNoConfigGroups [junit4] 2> 3122796 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNoConfigGroups-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4= ###Ending testProxyNoConfigGroups [junit4] 2> 3122815 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidProxyUser-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCase= J4 ###Starting testProxyInvalidProxyUser [junit4] 2> 3122817 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidProxyUser-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCase= J4 ###Ending testProxyInvalidProxyUser [junit4] 2> 3122844 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 ###Start= ing testForwarding [junit4] 2> 3123524 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3123527 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.c.s.i.ZkClientClusterSt= ateProvider Cluster at 127.0.0.1:36781/solr ready [junit4] 2> 3123528 INFO (qtp295414161-28905) [n:127.0.0.1:46679_solr= ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with p= arams pullReplicas=3D0&replicationFactor=3D1&collection.configName=3Dconf1&= maxShardsPerNode=3D1&name=3DforwardingCollection&nrtReplicas=3D1&action=3DC= REATE&numShards=3D1&tlogReplicas=3D0&wt=3Djavabin&version=3D2 and sendToOCP= Queue=3Dtrue [junit4] 2> 3123532 INFO (OverseerThreadFactory-9947-thread-1-process= ing-n:127.0.0.1:49771_solr) [n:127.0.0.1:49771_solr ] o.a.s.c.CreateColl= ectionCmd Create collection forwardingCollection [junit4] 2> 3123648 INFO (OverseerStateUpdate-98956891161886725-127.0= .0.1:49771_solr-n_0000000000) [n:127.0.0.1:49771_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:49771/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 3123849 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&co= reNodeName=3Dcore_node2&collection.configName=3Dconf1&newCollection=3Dtrue&= name=3DforwardingCollection_shard1_replica_n1&action=3DCREATE&numShards=3D1= &collection=3DforwardingCollection&shard=3Dshard1&wt=3Djavabin&version=3D2&= replicaType=3DNRT [junit4] 2> 3123849 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for = 2147483647 transient cores [junit4] 2> 3123955 INFO (zkCallback-5593-thread-1-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_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> 3123955 INFO (zkCallback-5593-thread-2-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_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> 3124896 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 3124929 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.s.IndexSchema [forwardingCollection_shard1_replica_n1] S= chema name=3Dtest [junit4] 2> 3125065 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field= id [junit4] 2> 3125096 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'forwardingCollection_= shard1_replica_n1' using configuration from collection forwardingCollection= , trusted=3Dtrue [junit4] 2> 3125097 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49771.solr.= core.forwardingCollection.shard1.replica_n1' (registry 'solr.core.forwardin= gCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@6b326590 [junit4] 2> 3125097 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 3125097 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.SolrCore [[forwardingCollection_shard1_replica_n1] ] O= pening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-S= olaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecure= Impersonation_1F9CA8CD46E821A4-001/tempDir-001/node2/forwardingCollection_s= hard1_replica_n1], dataDir=3D[/export/home/jenkins/workspace/Lucene-Solr-ma= ster-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWith= SecureImpersonation_1F9CA8CD46E821A4-001/tempDir-001/node2/./forwardingColl= ection_shard1_replica_n1/data/] [junit4] 2> 3125106 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org= .apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMe= rgeSize=3D1677721, mergeFactor=3D15, maxMergeSize=3D2147483648, maxMergeSiz= eForForcedMerge=3D9223372036854775807, calibrateSizeByDeletes=3Dfalse, maxM= ergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRat= io=3D1.0] [junit4] 2> 3125201 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apac= he.solr.update.UpdateLog [junit4] 2> 3125201 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSy= ncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuck= ets=3D65536 [junit4] 2> 3125202 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3125202 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3125203 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org= .apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMe= rgeSize=3D1677721, mergeFactor=3D45, maxMergeSize=3D2147483648, maxMergeSiz= eForForcedMerge=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMe= rgeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRati= o=3D0.15791779474687467] [junit4] 2> 3125204 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a8f79e7[forwardin= gCollection_shard1_replica_n1] main] [junit4] 2> 3125244 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 3125261 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=3Ddirect,classname= =3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLength=3D3} [junit4] 2> 3125341 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000m= s. [junit4] 2> 3125341 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recen= t updates, using new clock 1583310261936193536 [junit4] 2> 3125343 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125344 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125344 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125345 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125345 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125346 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125346 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125346 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125347 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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> 3125356 INFO (searcherExecutor-9952-thread-1-processing-n= :127.0.0.1:49771_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:f= orwardingCollection r:core_node2) [n:127.0.0.1:49771_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@7a8f79e7[forwardingCollection_shard1_replica_n1] main{ExitableDi= rectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3125377 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to co= ntinue. [junit4] 2> 3125377 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - t= ry and sync [junit4] 2> 3125377 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49771/s= olr/forwardingCollection_shard1_replica_n1/ [junit4] 2> 3125377 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3125377 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:49771/solr/forwardingCol= lection_shard1_replica_n1/ has no replicas [junit4] 2> 3125377 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas particip= ating in election, clear LIR [junit4] 2> 3125385 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http:/= /127.0.0.1:49771/solr/forwardingCollection_shard1_replica_n1/ shard1 [junit4] 2> 3125537 INFO (zkCallback-5593-thread-1-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_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> 3125537 INFO (zkCallback-5593-thread-2-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_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> 3125588 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3125605 INFO (qtp378947841-28908) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores= params=3D{qt=3D/admin/cores&coreNodeName=3Dcore_node2&collection.configNam= e=3Dconf1&newCollection=3Dtrue&name=3DforwardingCollection_shard1_replica_n= 1&action=3DCREATE&numShards=3D1&collection=3DforwardingCollection&shard=3Ds= hard1&wt=3Djavabin&version=3D2&replicaType=3DNRT} status=3D0 QTime=3D1756 [junit4] 2> 3125623 INFO (qtp295414161-28905) [n:127.0.0.1:46679_solr= ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for= at most 30 seconds. Check all shard replicas [junit4] 2> 3125714 INFO (zkCallback-5593-thread-2-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_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> 3125714 INFO (zkCallback-5593-thread-1-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_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> 3126631 INFO (qtp295414161-28905) [n:127.0.0.1:46679_solr= ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections = params=3D{pullReplicas=3D0&replicationFactor=3D1&collection.configName=3Dco= nf1&maxShardsPerNode=3D1&name=3DforwardingCollection&nrtReplicas=3D1&action= =3DCREATE&numShards=3D1&tlogReplicas=3D0&wt=3Djavabin&version=3D2} status= =3D0 QTime=3D3103 [junit4] 2> 3126632 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.c.AbstractDistribZkTest= Base Wait for recoveries to finish - collection: forwardingCollection failO= nTimeout:true timeout (sec):100 [junit4] 2> 3126633 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.c.AbstractDistribZkTest= Base Recoveries finished - collection: forwardingCollection [junit4] 2> 3126637 INFO (qtp295414161-28917) [n:127.0.0.1:46679_solr= ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for = 2147483647 transient cores [junit4] 2> 3126643 INFO (qtp378947841-28912) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.S.Request [forwardingCollection_shard1_replica_n1] we= bapp=3D/solr path=3D/select params=3D{q=3D*:*&wt=3Djavabin&version=3D2} hit= s=3D0 status=3D0 QTime=3D0 [junit4] 2> 3126648 INFO (qtp378947841-28913) [n:127.0.0.1:49771_solr= c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1= _replica_n1] o.a.s.c.S.Request [forwardingCollection_shard1_replica_n1] we= bapp=3D/solr path=3D/select params=3D{q=3D*:*&wt=3Djavabin&version=3D2} hit= s=3D0 status=3D0 QTime=3D0 [junit4] 2> 3126649 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 ###Endin= g testForwarding [junit4] 2> 3126741 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateHost-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 #= ##Starting testProxyValidateHost [junit4] 2> 3126743 INFO (qtp295414161-28907) [n:127.0.0.1:46679_solr= ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with par= ams doAs=3Dbar&action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQueue=3D= true [junit4] 2> 3126752 INFO (qtp295414161-28907) [n:127.0.0.1:46679_solr= ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections = params=3D{doAs=3Dbar&action=3DLIST&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D8 [junit4] 2> 3126752 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateHost-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 #= ##Ending testProxyValidateHost [junit4] 2> 3126773 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateAnyHostAnyUser-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTe= stCaseJ4 ###Starting testProxyValidateAnyHostAnyUser [junit4] 2> 3126776 INFO (qtp295414161-28904) [n:127.0.0.1:46679_solr= ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with par= ams doAs=3Dbar&action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQueue=3D= true [junit4] 2> 3126782 INFO (qtp295414161-28904) [n:127.0.0.1:46679_solr= ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections = params=3D{doAs=3Dbar&action=3DLIST&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D6 [junit4] 2> 3126785 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateAnyHostAnyUser-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTe= stCaseJ4 ###Ending testProxyValidateAnyHostAnyUser [junit4] 2> 3126881 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateGroup-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyValidateGroup [junit4] 2> 3126928 INFO (qtp295414161-28919) [n:127.0.0.1:46679_solr= ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with par= ams doAs=3Djenkins&action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQueu= e=3Dtrue [junit4] 2> 3126936 INFO (qtp295414161-28919) [n:127.0.0.1:46679_solr= ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections = params=3D{doAs=3Djenkins&action=3DLIST&wt=3Djavabin&version=3D2} status=3D0= QTime=3D7 [junit4] 2> 3126936 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyValidateGroup-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyValidateGroup [junit4] 2> 3127093 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidRemote-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyInvalidRemote [junit4] 2> 3127117 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidRemote-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyInvalidRemote [junit4] 2> 3127223 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyUnknownRemote-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyUnknownRemote [junit4] 2> 3140421 WARN (jetty-launcher-5582-thread-1-SendThread(127= .0.0.1:36781)) [n:127.0.0.1:46679_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 13490ms for sessionid 0x15f90c4a9= c80006 [junit4] 2> 3140421 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x15f90c4a9c80006, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3140421 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyUnknownRemote-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testProxyUnknownRemote [junit4] 2> 3140425 WARN (jetty-launcher-5582-thread-2-SendThread(127= .0.0.1:36781)) [n:127.0.0.1:49771_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 17761ms for sessionid 0x15f90c4a9= c80008 [junit4] 2> 3140425 WARN (jetty-launcher-5582-thread-1-SendThread(127= .0.0.1:36781)) [n:127.0.0.1:46679_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 17763ms for sessionid 0x15f90c4a9= c80007 [junit4] 2> 3140426 WARN (jetty-launcher-5582-thread-2-SendThread(127= .0.0.1:36781)) [n:127.0.0.1:49771_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 14710ms for sessionid 0x15f90c4a9= c80005 [junit4] 2> 3140426 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x15f90c4a9c80008, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3140451 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x15f90c4a9c80007, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3140456 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x15f90c4a9c80= 005 due to java.io.IOException: Connection reset by peer [junit4] 2> 3140490 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyWrongHost-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 ###S= tarting testProxyWrongHost [junit4] 2> 3140552 WARN (zkCallback-5594-thread-1-processing-n:127.0= .0.1:46679_solr) [n:127.0.0.1:46679_solr ] o.a.s.c.c.ConnectionManager W= atcher org.apache.solr.common.cloud.ConnectionManager@63d6563 name: ZooKeep= erConnection Watcher:127.0.0.1:36781/solr got event WatchedEvent state:Disc= onnected type:None path:null path: null type: None [junit4] 2> 3140553 WARN (zkCallback-5594-thread-1-processing-n:127.0= .0.1:46679_solr) [n:127.0.0.1:46679_solr ] o.a.s.c.c.ConnectionManager z= kClient has disconnected [junit4] 2> 3140558 WARN (zkCallback-5593-thread-1-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_solr ] o.a.s.c.c.ConnectionManager W= atcher org.apache.solr.common.cloud.ConnectionManager@50981d4 name: ZooKeep= erConnection Watcher:127.0.0.1:36781/solr got event WatchedEvent state:Disc= onnected type:None path:null path: null type: None [junit4] 2> 3140559 WARN (zkCallback-5593-thread-1-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_solr ] o.a.s.c.c.ConnectionManager z= kClient has disconnected [junit4] 2> 3140558 WARN (zkCallback-5602-thread-4) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cf= 6dcdf name: ZooKeeperConnection Watcher:127.0.0.1:36781/solr got event Watc= hedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 3140560 WARN (zkCallback-5602-thread-4) [ ] o.a.s.c.c.= ConnectionManager zkClient has disconnected [junit4] 2> 3142404 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyWrongHost-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 ###E= nding testProxyWrongHost [junit4] 2> 3142411 INFO (jetty-launcher-5582-thread-1-EventThread) [= n:127.0.0.1:46679_solr ] o.a.c.f.s.ConnectionStateManager State change: = SUSPENDED [junit4] 2> 3142423 INFO (jetty-launcher-5582-thread-2-EventThread) [= n:127.0.0.1:49771_solr ] o.a.c.f.s.ConnectionStateManager State change: = SUSPENDED [junit4] 2> 3142426 INFO (OverseerCollectionConfigSetProcessor-989568= 91161886725-127.0.0.1:49771_solr-n_0000000000) [n:127.0.0.1:49771_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> 3142462 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidGroup-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 #= ##Starting testProxyInvalidGroup [junit4] 2> 3142487 WARN (qtp295414161-28918) [ ] o.a.h.s.ShellBas= edUnixGroupsMapping got exception trying to get groups for user bar: id: in= valid user name: "bar" [junit4] 2> id: invalid user name: "bar" [junit4] 2>=20 [junit4] 2> 3142489 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidGroup-seed#[1F9CA8CD46E821A4]) [ ] o.a.s.SolrTestCaseJ4 #= ##Ending testProxyInvalidGroup [junit4] 2> 3142556 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estForwarding-seed#[1F9CA8CD46E821A4]-SendThread(127.0.0.1:36781)) [ ] o= .a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x15f90c4= a9c8000b has expired [junit4] 2> 3142557 WARN (zkCallback-5602-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cf= 6dcdf name: ZooKeeperConnection Watcher:127.0.0.1:36781/solr got event Watc= hedEvent state:Expired type:None path:null path: null type: None [junit4] 2> 3142557 WARN (zkCallback-5602-thread-1) [ ] o.a.s.c.c.= ConnectionManager Our previous ZooKeeper session was expired. Attempting to= reconnect to recover relationship with ZooKeeper... [junit4] 2> 3142557 WARN (zkCallback-5602-thread-1) [ ] o.a.s.c.c.= DefaultConnectionStrategy Connection expired - starting a new one... [junit4] 2> 3142559 INFO (zkCallback-5602-thread-1) [ ] o.a.s.c.c.= ConnectionManager Connection with ZooKeeper reestablished. [junit4] 2> 3142661 ERROR (zkCallback-5602-thread-1) [ ] o.a.s.c.c.= ZkStateReader A ZK error has occurred [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredExcepti= on: KeeperErrorCode =3D Session expired for /clusterstate.json [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java= :1102) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.lambda$exi= sts$3(SolrZkClient.java:308) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.exists(Sol= rZkClient.java:308) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.createClu= sterStateWatchersAndUpdate(ZkStateReader.java:428) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader$1.command= (ZkStateReader.java:283) [junit4] 2> =09at org.apache.solr.common.cloud.ConnectionManager$1.upd= ate(ConnectionManager.java:168) [junit4] 2> =09at org.apache.solr.common.cloud.DefaultConnectionStrate= gy.reconnect(DefaultConnectionStrategy.java:57) [junit4] 2> =09at org.apache.solr.common.cloud.ConnectionManager.proce= ss(ConnectionManager.java:142) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$1.lambda$p= rocess$0(SolrZkClient.java:262) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1149) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:624) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3142661 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x15f90c4a9c8000c, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3142661 ERROR (zkCallback-5602-thread-1) [ ] o.a.s.c.c.= DefaultConnectionStrategy Reconnect to ZooKeeper failed:org.apache.solr.com= mon.cloud.ZooKeeperException: A ZK error has occurred [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader$1.command= (ZkStateReader.java:286) [junit4] 2> =09at org.apache.solr.common.cloud.ConnectionManager$1.upd= ate(ConnectionManager.java:168) [junit4] 2> =09at org.apache.solr.common.cloud.DefaultConnectionStrate= gy.reconnect(DefaultConnectionStrategy.java:57) [junit4] 2> =09at org.apache.solr.common.cloud.ConnectionManager.proce= ss(ConnectionManager.java:142) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$1.lambda$p= rocess$0(SolrZkClient.java:262) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1149) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:624) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExp= iredException: KeeperErrorCode =3D Session expired for /clusterstate.json [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java= :1102) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.lambda$exi= sts$3(SolrZkClient.java:308) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.exists(Sol= rZkClient.java:308) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.createClu= sterStateWatchersAndUpdate(ZkStateReader.java:428) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader$1.command= (ZkStateReader.java:283) [junit4] 2> =09... 10 more [junit4] 2>=20 [junit4] 2> 3142662 WARN (zkCallback-5602-thread-1) [ ] o.a.s.c.c.= DefaultConnectionStrategy Reconnect to ZooKeeper failed [junit4] 2> 3142662 INFO (zkCallback-5602-thread-1) [ ] o.a.s.c.c.= ConnectionManager Connected:true [junit4] 2> 3142664 INFO (jetty-closer-5583-thread-2) [ ] o.e.j.s.= AbstractConnector Stopped ServerConnector@12f47cb6{HTTP/1.1,[http/1.1]}{127= .0.0.1:0} [junit4] 2> 3142664 INFO (jetty-closer-5583-thread-1) [ ] o.e.j.s.= AbstractConnector Stopped ServerConnector@73e549cd{HTTP/1.1,[http/1.1]}{127= .0.0.1:0} [junit4] 2> 3142664 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.c.= CoreContainer Shutting down CoreContainer instance=3D2016992943 [junit4] 2> 3142665 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.node, tag=3D= null [junit4] 2> 3142665 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@1e07b2dc: rootName =3D solr_49771, domain =3D solr.node, service= url =3D null, agent id =3D null] for registry solr.node / com.codahale.met= rics.MetricRegistry@1600a056 [junit4] 2> 3142670 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jvm, tag=3Dn= ull [junit4] 2> 3142671 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@4672365d: rootName =3D solr_49771, domain =3D solr.jvm, service = url =3D null, agent id =3D null] for registry solr.jvm / com.codahale.metri= cs.MetricRegistry@5ff64bf9 [junit4] 2> 3142672 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.c.= CoreContainer Shutting down CoreContainer instance=3D1747613945 [junit4] 2> 3142680 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.node, tag=3D= null [junit4] 2> 3142680 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@25f80d14: rootName =3D solr_46679, domain =3D solr.node, service= url =3D null, agent id =3D null] for registry solr.node / com.codahale.met= rics.MetricRegistry@51e3122a [junit4] 2> 3142683 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag= =3Dnull [junit4] 2> 3142683 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@476e5ea5: rootName =3D solr_49771, domain =3D solr.jetty, servic= e url =3D null, agent id =3D null] for registry solr.jetty / com.codahale.m= etrics.MetricRegistry@727bffd1 [junit4] 2> 3142685 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jvm, tag=3Dn= ull [junit4] 2> 3142686 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@66e35361: rootName =3D solr_46679, domain =3D solr.jvm, service = url =3D null, agent id =3D null] for registry solr.jvm / com.codahale.metri= cs.MetricRegistry@5ff64bf9 [junit4] 2> 3142687 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.c.= ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:49771_s= olr [junit4] 2> 3142690 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag= =3Dnull [junit4] 2> 3142690 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@5d78360b: rootName =3D solr_46679, domain =3D solr.jetty, servic= e url =3D null, agent id =3D null] for registry solr.jetty / com.codahale.m= etrics.MetricRegistry@727bffd1 [junit4] 2> 3142693 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag= =3Dnull [junit4] 2> 3142693 INFO (zkCallback-5594-thread-6-processing-n:127.0= .0.1:46679_solr) [n:127.0.0.1:46679_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 3142693 INFO (zkCallback-5593-thread-5-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 3142695 INFO (coreCloseExecutor-9957-thread-1) [n:127.0.0= .1:49771_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@2709e502 [junit4] 2> 3142696 INFO (coreCloseExecutor-9957-thread-1) [n:127.0.0= .1:49771_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=3D65= 4959874 [junit4] 2> 3142696 INFO (coreCloseExecutor-9957-thread-1) [n:127.0.0= .1:49771_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@6628a879: rootName =3D solr_49= 771, 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@17a19dcf [junit4] 2> 3142700 INFO (zkCallback-5593-thread-5-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_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> 3142700 INFO (zkCallback-5593-thread-3-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_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> 3142703 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.c.= ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46679_s= olr [junit4] 2> 3142705 INFO (jetty-closer-5583-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag= =3Dnull [junit4] 2> 3142713 INFO (zkCallback-5594-thread-6-processing-n:127.0= .0.1:46679_solr) [n:127.0.0.1:46679_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 3142713 INFO (zkCallback-5593-thread-3-processing-n:127.0= .0.1:49771_solr) [n:127.0.0.1:49771_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 3142714 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x15f90c4a9c80006, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3142725 ERROR (Thread[Thread-7630,5,TGRP-TestSolrCloudWith= SecureImpersonation]) [n:127.0.0.1:46679_solr ] o.a.h.s.t.d.AbstractDele= gationTokenSecretManager ExpiredTokenRemover received java.lang.Interrupted= Exception: sleep interrupted [junit4] 2> 3142729 INFO (jetty-closer-5583-thread-1) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7da80e0a{/solr,null,= UNAVAILABLE} [junit4] 2> 3142730 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x15f90c4a9c80007, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3142733 INFO (coreCloseExecutor-9957-thread-1) [n:127.0.0= .1:49771_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= 654959874 [junit4] 2> 3142735 INFO (jetty-closer-5583-thread-2) [ ] o.a.s.c.= Overseer Overseer (id=3D98956891161886725-127.0.0.1:49771_solr-n_0000000000= ) closing [junit4] 2> 3142735 INFO (OverseerStateUpdate-98956891161886725-127.0= .0.1:49771_solr-n_0000000000) [n:127.0.0.1:49771_solr ] o.a.s.c.Overseer= Overseer Loop exiting : 127.0.0.1:49771_solr [junit4] 2> 3142740 WARN (OverseerAutoScalingTriggerThread-9895689116= 1886725-127.0.0.1:49771_solr-n_0000000000) [n:127.0.0.1:49771_solr ] o.a= .s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are clos= ed, exiting. [junit4] 2> 3142750 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x15f90c4a9c80005, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3142753 ERROR (Thread[Thread-7632,5,TGRP-TestSolrCloudWith= SecureImpersonation]) [n:127.0.0.1:49771_solr ] o.a.h.s.t.d.AbstractDele= gationTokenSecretManager ExpiredTokenRemover received java.lang.Interrupted= Exception: sleep interrupted [junit4] 2> 3142757 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x15f90c4a9c80008, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3142759 INFO (jetty-closer-5583-thread-2) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@12cc8058{/solr,null,= UNAVAILABLE} [junit4] 2> 3142762 ERROR (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-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> 3142763 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-worker) [ ] o.a.s.c.ZkTestServer connecting to 1= 27.0.0.1:36781 36781 [junit4] 2> 3145179 INFO (Thread-7613) [ ] o.a.s.c.ZkTestServer co= nnecting to 127.0.0.1:36781 36781 [junit4] 2> 3145180 WARN (Thread-7613) [ ] 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>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =096=09/solr/collections/forwardingCollection/state.json [junit4] 2> =094=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot [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>=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> =093=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot [junit4] 2>=20 [junit4] 2> 3145294 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[1F9CA8CD46E821A4]-worker) [ ] o.a.s.SolrTestCaseJ4 ---------------= ---------------------------------------- Done waiting for tracked resources= to be released [junit4] 2> Nov 06, 2017 9:59:11 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thre= ad(s). [junit4] 2> Nov 06, 2017 9:59:31 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 2 threads leaked from SUITE scope at org.apache.so= lr.cloud.TestSolrCloudWithSecureImpersonation:=20 [junit4] 2> 1) Thread[id=3D28961, name=3Djetty-launcher-5582-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:530) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:505) [junit4] 2> 2) Thread[id=3D28951, name=3Djetty-launcher-5582-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:530) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:505) [junit4] 2> Nov 06, 2017 9:59:31 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=3D28961, name=3Djetty-launcher-5582-thread= -2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] 2> 2) Thread[id=3D28951, name=3Djetty-launcher-5582-thread= -1-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] 2> 3165494 ERROR (jetty-launcher-5582-thread-2-EventThread) [= n:127.0.0.1:49771_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:530) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:505) [junit4] 2> 3165494 ERROR (jetty-launcher-5582-thread-1-EventThread) [= n:127.0.0.1:46679_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:530) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:505) [junit4] 2> Nov 06, 2017 9:59:31 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=3DFastDecompressionCompressin= gStoredFields(storedFieldsFormat=3DCompressingStoredFieldsFormat(compressio= nMode=3DFAST_DECOMPRESSION, chunkSize=3D30528, maxDocsPerChunk=3D274, block= Size=3D5), termVectorsFormat=3DCompressingTermVectorsFormat(compressionMode= =3DFAST_DECOMPRESSION, chunkSize=3D30528, blockSize=3D5)), sim=3DAsserting(= org.apache.lucene.search.similarities.AssertingSimilarity@9845c2d), locale= =3Dga, timezone=3DAmerica/Guayaquil [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bi= t)/cpus=3D3,threads=3D1,free=3D124949456,total=3D518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestShardHandlerFactory,= TestFastLRUCache, CheckHdfsIndexTest, HdfsWriteToMultipleCollectionsTest, = CdcrBootstrapTest, TestPerFieldSimilarity, TestStressReorder, TriLevelCompo= siteIdRoutingTest, SolrMetricReporterTest, RankQueryTest, ResponseHeaderTes= t, TestSubQueryTransformer, TestSolrJ, TestSolrDeletionPolicy1, ZkNodeProps= Test, TestDeleteCollectionOnDownNodes, TestSystemIdResolver, QueryResultKey= Test, TestConfigSetsAPI, TestImpersonationWithHadoopAuth, CdcrUpdateLogTest= , TestScoreJoinQPScore, ShardRoutingCustomTest, TestClassicSimilarityFactor= y, DistributedIntervalFacetingTest, TestReqParamsAPI, TestSchemaSimilarityR= esource, SharedFSAutoReplicaFailoverTest, TestLRUCache, JavabinLoaderTest, = BitVectorTest, TestFastWriter, FacetPivotSmallTest, CollectionsAPIAsyncDist= ributedZkTest, TestObjectReleaseTracker, TestMergePolicyConfig, BufferStore= Test, DirectSolrSpellCheckerTest, TestExpandComponent, TestCustomSort, Solr= CoreMetricManagerTest, TestDownShardTolerantSearch, TestManagedResourceStor= age, TestSQLHandler, HdfsBasicDistributedZkTest, PKIAuthenticationIntegrati= onTest, ConnectionManagerTest, TestSchemaNameResource, NodeAddedTriggerTest= , SolrCoreCheckLockOnStartupTest, TestRandomFaceting, TestAnalyzedSuggestio= ns, SubstringBytesRefFilterTest, TestNumericTerms64, AnalyticsQueryTest, Te= stPrepRecovery, TestIndexSearcher, PeerSyncTest, RemoteQueryErrorTest, Bool= eanFieldTest, TestCloudPseudoReturnFields, SOLR749Test, DOMUtilTest, TestNa= medUpdateProcessors, TestBM25SimilarityFactory, TestFastOutputStream, Chaos= MonkeySafeLeaderTest, NodeMutatorTest, SimpleFacetsTest, TestHalfAndHalfDoc= Values, TestLegacyFieldCache, TestNonDefinedSimilarityFactory, TestDocSet, = DocValuesTest, TestLuceneMatchVersion, DistributedQueueTest, MoveReplicaTes= t, TestFieldCollectionResource, TestBulkSchemaAPI, SolrPluginUtilsTest, Bas= icAuthIntegrationTest, UpdateRequestProcessorFactoryTest, DocExpirationUpda= teProcessorFactoryTest, TestLegacyFieldReuse, StatsReloadRaceTest, BigEndia= nAscendingWordDeserializerTest, DistributedMLTComponentTest, BasicZkTest, H= ealthCheckHandlerTest, HdfsAutoAddReplicasIntegrationTest, ChaosMonkeyNothi= ngIsSafeWithPullReplicasTest, TestMissingGroups, SystemInfoHandlerTest, Bad= ComponentTest, TestFieldTypeCollectionResource, SecurityConfHandlerTest, Di= stributedFacetPivotWhiteBoxTest, TestConfigSetProperties, LukeRequestHandle= rTest, ExternalFileFieldSortTest, TestNestedDocsSort, TestLegacyTerms, Over= riddenZkACLAndCredentialsProvidersTest, TestDocBasedVersionConstraints, Cor= eAdminHandlerTest, TestZkAclsWithHadoopAuth, FullSolrCloudDistribCmdsTest, = FileBasedSpellCheckerTest, PluginInfoTest, TestPivotHelperCode, TestSimpleT= rackingShardHandler, TestAddFieldRealTimeGet, ClassificationUpdateProcessor= Test, TestConfig, TestFreeTextSuggestions, TestManagedSchemaAPI, SolrXmlInZ= kTest, LeaderFailureAfterFreshStartTest, ManagedSchemaRoundRobinCloudTest, = MoveReplicaHDFSFailoverTest, TestSubQueryTransformerDistrib, SolrSlf4jRepor= terTest, TestRequestStatusCollectionAPI, TestTrieFacet, StatelessScriptUpda= teProcessorFactoryTest, SpellCheckCollatorWithCollapseTest, TestCSVLoader, = TestStressLucene, CurrencyFieldTypeTest, HdfsNNFailoverTest, TestEmbeddedSo= lrServerConstructors, PingRequestHandlerTest, DocumentAnalysisRequestHandle= rTest, TestFaceting, TestFilteredDocIdSet, TestSearchPerf, TestSort, TestDy= namicFieldCollectionResource, TestExactStatsCache, TestCloudJSONFacetJoinDo= main, UpdateParamsTest, SearchHandlerTest, TestLegacyNumericRangeQueryBuild= er, PeerSyncWithIndexFingerprintCachingTest, TestTrie, JsonLoaderTest, Test= ComponentsName, ClassificationUpdateProcessorFactoryTest, ParsingFieldUpdat= eProcessorsTest, AliasIntegrationTest, DistributedFacetPivotLargeTest, Lead= erElectionIntegrationTest, TestDynamicLoading, CloudMLTQParserTest, TestLea= derElectionZkExpiry, TestCloudSearcherWarming, DeleteReplicaTest, ComputePl= anActionTest, TestQueryUtils, MigrateRouteKeyTest, ZkSolrClientTest, TestRT= GBase, TestPostingsSolrHighlighter, HdfsTlogReplayBufferedWhileIndexingTest= , TestDistributedGrouping, TestHdfsCloudBackupRestore, SolrCoreTest, Overse= erModifyCollectionTest, TestPKIAuthenticationPlugin, DebugComponentTest, Te= stCollectionAPIs, TestNRTOpen, BlobRepositoryCloudTest, TestReload, TestSha= 256AuthenticationProvider, TestHdfsBackupRestoreCore, ConcurrentDeleteAndCr= eateCollectionTest, DocValuesMultiTest, DeleteLastCustomShardedReplicaTest,= ActionThrottleTest, TestSerializedLuceneMatchVersion, TestQueryWrapperFilt= er, TestFieldCache, TestStressInPlaceUpdates, BasicDistributedZkTest, Basic= DistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTes= t, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorCompo= nentDistributedTest, TestStressVersions, DistributedTermsComponentTest, Tes= tGroupingSearch, BadIndexSchemaTest, ConvertedLegacyTest, TestFunctionQuery= , DirectUpdateHandlerTest, TestBadConfig, SoftAutoCommitTest, PreAnalyzedFi= eldTest, PrimUtilsTest, TestSuggestSpellingConverter, DateFieldTest, Spelli= ngQueryConverterTest, RAMDirectoryFactoryTest, TestDocumentBuilder, FileUti= lsTest, CircularListTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursor= MarkWithoutUniqueKey, TestDistributedMissingSort, TestLuceneIndexBackCompat= , TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerSchemaAPI, Test= JettySolrRunner, AssignBackwardCompatibilityTest, CloudExitableDirectoryRea= derTest, CollectionReloadTest, CollectionStateFormat2Test, CollectionsAPISo= lrJTest, DeleteInactiveReplicaTest, DistribDocExpirationUpdateProcessorTest= , DocValuesNotIndexedTest, LeaderElectionContextKeyTest, LeaderInitiatedRec= overyOnCommitTest, LeaderInitiatedRecoveryOnShardRestartTest, MissingSegmen= tRecoveryTest, MoveReplicaHDFSTest, OutOfBoxZkACLAndCredentialsProvidersTes= t, OverseerStatusTest, PeerSyncReplicationTest, ReplaceNodeTest, SSLMigrati= onTest, SaslZkACLProviderTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, Te= stCloudPivotFacet, TestConfigSetsAPIZkFailure, TestExclusionRuleCollectionA= ccess, TestLeaderElectionWithEmptyReplica, TestLocalFSCloudBackupRestore, T= estPullReplica, TestRandomRequestDistribution, TestReplicaProperties, TestS= egmentSorting, TestSkipOverseerOperations, TestSolrCloudWithDelegationToken= s, TestSolrCloudWithKerberosAlt, TestSolrCloudWithSecureImpersonation] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestSolrCloud= WithSecureImpersonation -Dtests.seed=3D1F9CA8CD46E821A4 -Dtests.slow=3Dtrue= -Dtests.locale=3Dga -Dtests.timezone=3DAmerica/Guayaquil -Dtests.asserts= =3Dtrue -Dtests.file.encoding=3DUTF-8 [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=3D28961, name=3Djetty-launcher-5582-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:530) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:505) [junit4] > 2) Thread[id=3D28951, name=3Djetty-launcher-5582-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:530) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:505) [junit4] > =09at __randomizedtesting.SeedInfo.seed([1F9CA8CD46E821A4]= :0) [junit4] Completed [557/749 (1!)] on J1 in 43.43s, 12 tests, 1 error <<<= FAILURES! [...truncated 47796 lines...] ------=_Part_386_1261961011.1509964601675 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_386_1261961011.1509964601675--