From dev-return-312457-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Thu Feb 22 10:09:42 2018 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by mx-eu-01.ponee.io (Postfix) with SMTP id 92F8718064E for ; Thu, 22 Feb 2018 10:09:39 +0100 (CET) Received: (qmail 83404 invoked by uid 500); 22 Feb 2018 09:09:38 -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 83390 invoked by uid 99); 22 Feb 2018 09:09:37 -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; Thu, 22 Feb 2018 09:09:37 +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 C20E6180404 for ; Thu, 22 Feb 2018 09:09:36 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -2.301 X-Spam-Level: X-Spam-Status: No, score=-2.301 tagged_above=-999 required=6.31 tests=[RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-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 suOnR8U_qWYM for ; Thu, 22 Feb 2018 09:09:21 +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 E1E445F124 for ; Thu, 22 Feb 2018 09:09:20 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id EEF5310805F4 for ; Thu, 22 Feb 2018 09:09:19 +0000 (UTC) Date: Thu, 22 Feb 2018 09:07:57 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <168005087.17.1519290559979.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <569886352.171.1519214187730.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <569886352.171.1519214187730.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 456 - Still Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_16_1550822611.1519290559902" X-Jenkins-Job: Lucene-Solr-7.x-Solaris X-Jenkins-Result: UNSTABLE ------=_Part_16_1550822611.1519290559902 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/456/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC 2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.security.hadoop.TestImpe= rsonationWithHadoopAuth Error Message: 1 thread leaked from SUITE scope at org.apache.solr.security.hadoop.TestImp= ersonationWithHadoopAuth: 1) Thread[id=3D9893, name=3Djetty-launcher-18= 96-thread-2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestImpersonat= ionWithHadoopAuth] at sun.misc.Unsafe.park(Native Method) a= t java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) = at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSha= redNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurr= ent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSy= nchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(= CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClie= nt.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) = at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) = at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForegro= und(GetDataBuilderImpl.java:288) at org.apache.curator.framework.im= ps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.a= pache.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.re= cipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apa= che.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java= :61) at org.apache.curator.framework.imps.NamespaceWatcher.process(= NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventT= hread.processEvent(ClientCnxn.java:531) at org.apache.zookeeper.Cli= entCnxn$EventThread.run(ClientCnxn.java:506) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SU= ITE scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAut= h:=20 1) Thread[id=3D9893, name=3Djetty-launcher-1896-thread-2-EventThread, st= ate=3DTIMED_WAITING, group=3DTGRP-TestImpersonationWithHadoopAuth] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireS= haredNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquire= SharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:27= 7) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConn= ectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeg= round(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(Get= DataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(Get= DataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValu= e(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$1= 00(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.proces= s(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(Names= paceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientC= nxn.java:531) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:= 506) =09at __randomizedtesting.SeedInfo.seed([664B7BE5FCE5DCCD]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithS= ecureImpersonation Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWith= SecureImpersonation: 1) Thread[id=3D35909, name=3Djetty-launcher-8334-t= hread-1-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithS= ecureImpersonation] at sun.misc.Unsafe.park(Native Method) = at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) = at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSh= aredNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concur= rent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedS= ynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await= (CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperCli= ent.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) = at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) = at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForegr= ound(GetDataBuilderImpl.java:288) at org.apache.curator.framework.i= mps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.= apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl= .java:41) at org.apache.curator.framework.recipes.shared.SharedValu= e.readValue(SharedValue.java:244) at org.apache.curator.framework.r= ecipes.shared.SharedValue.access$100(SharedValue.java:44) at org.ap= ache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.jav= a:61) at org.apache.curator.framework.imps.NamespaceWatcher.process= (NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$Event= Thread.processEvent(ClientCnxn.java:531) at org.apache.zookeeper.Cl= ientCnxn$EventThread.run(ClientCnxn.java:506) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SU= ITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:=20 1) Thread[id=3D35909, name=3Djetty-launcher-8334-thread-1-EventThread, s= tate=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.jav= a:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireS= haredNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquire= SharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:27= 7) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConn= ectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeg= round(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(Get= DataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(Get= DataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValu= e(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$1= 00(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.proces= s(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(Names= paceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientC= nxn.java:531) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:= 506) =09at __randomizedtesting.SeedInfo.seed([664B7BE5FCE5DCCD]:0) Build Log: [...truncated 12740 lines...] [junit4] Suite: org.apache.solr.security.hadoop.TestImpersonationWithHad= oopAuth [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-So= lr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestI= mpersonationWithHadoopAuth_664B7BE5FCE5DCCD-001/init-core-data-001 [junit4] 2> 1149761 WARN (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearche= rs: numOpens=3D1 numCloses=3D1 [junit4] 2> 1149761 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (N= UMERIC_POINTS_SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 1149763 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (fals= e) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D= , value=3DNaN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 1149763 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity = checks: test.solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/d= ev/./urandom [junit4] 2> 1149801 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting clu= ster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris= /solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestImpersonationWi= thHadoopAuth_664B7BE5FCE5DCCD-001/tempDir-001 [junit4] 2> 1149801 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SER= VER [junit4] 2> 1149801 INFO (Thread-3631) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1149801 INFO (Thread-3631) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1149803 ERROR (Thread-3631) [ ] 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> 1149905 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.ZkTestServer start zk server on p= ort:65167 [junit4] 2> 1149914 INFO (zkConnectionManagerCallback-1899-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1149942 INFO (jetty-launcher-1896-thread-2) [ ] o.e.j.= s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git = hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1149944 INFO (jetty-launcher-1896-thread-2) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 1149944 INFO (jetty-launcher-1896-thread-2) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 1149944 INFO (jetty-launcher-1896-thread-2) [ ] o.e.j.= s.session Scavenging every 660000ms [junit4] 2> 1149944 INFO (jetty-launcher-1896-thread-2) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c4a5f35{/solr,nul= l,AVAILABLE} [junit4] 2> 1149945 INFO (jetty-launcher-1896-thread-2) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@2cf24888{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:57442} [junit4] 2> 1149945 INFO (jetty-launcher-1896-thread-2) [ ] o.e.j.= s.Server Started @1153344ms [junit4] 2> 1149945 INFO (jetty-launcher-1896-thread-2) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D57= 442} [junit4] 2> 1149945 ERROR (jetty-launcher-1896-thread-2) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1149945 INFO (jetty-launcher-1896-thread-1) [ ] o.e.j.= s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git = hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1149945 INFO (jetty-launcher-1896-thread-2) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 7.3.0 [junit4] 2> 1149945 INFO (jetty-launcher-1896-thread-2) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1149945 INFO (jetty-launcher-1896-thread-2) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1149945 INFO (jetty-launcher-1896-thread-2) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-22T07:49:18.173Z [junit4] 2> 1149946 INFO (jetty-launcher-1896-thread-1) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 1149946 INFO (jetty-launcher-1896-thread-1) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 1149946 INFO (jetty-launcher-1896-thread-1) [ ] o.e.j.= s.session Scavenging every 660000ms [junit4] 2> 1149947 INFO (jetty-launcher-1896-thread-1) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@50ac8a59{/solr,nul= l,AVAILABLE} [junit4] 2> 1149948 INFO (jetty-launcher-1896-thread-1) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@fcffb5a{HTTP/1.1,[http/1.1]}{12= 7.0.0.1:60841} [junit4] 2> 1149948 INFO (jetty-launcher-1896-thread-1) [ ] o.e.j.= s.Server Started @1153347ms [junit4] 2> 1149948 INFO (jetty-launcher-1896-thread-1) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D60= 841} [junit4] 2> 1149948 ERROR (jetty-launcher-1896-thread-1) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1149948 INFO (jetty-launcher-1896-thread-1) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 7.3.0 [junit4] 2> 1149948 INFO (jetty-launcher-1896-thread-1) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1149948 INFO (jetty-launcher-1896-thread-1) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1149948 INFO (jetty-launcher-1896-thread-1) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-22T07:49:18.176Z [junit4] 2> 1149950 INFO (zkConnectionManagerCallback-1901-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1149951 INFO (jetty-launcher-1896-thread-2) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1149952 INFO (zkConnectionManagerCallback-1903-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1149952 INFO (jetty-launcher-1896-thread-1) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1149982 INFO (jetty-launcher-1896-thread-1) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:65167/solr [junit4] 2> 1149984 INFO (zkConnectionManagerCallback-1907-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1149997 INFO (jetty-launcher-1896-thread-2) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:65167/solr [junit4] 2> 1150001 INFO (zkConnectionManagerCallback-1913-thread-1-p= rocessing-n:127.0.0.1:60841_solr) [n:127.0.0.1:60841_solr ] o.a.s.c.c.Co= nnectionManager zkClient has connected [junit4] 2> 1150002 INFO (zkConnectionManagerCallback-1911-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1150015 INFO (zkConnectionManagerCallback-1915-thread-1-p= rocessing-n:127.0.0.1:57442_solr) [n:127.0.0.1:57442_solr ] o.a.s.c.c.Co= nnectionManager zkClient has connected [junit4] 2> 1150055 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1150056 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader= 127.0.0.1:60841_solr [junit4] 2> 1150062 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.c.Overseer Overseer (id=3D72241307093434372-127.0.0.1= :60841_solr-n_0000000000) starting [junit4] 2> 1150075 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:60841_solr [junit4] 2> 1150081 INFO (zkCallback-1912-thread-1-processing-n:127.0= .0.1:60841_solr) [n:127.0.0.1:60841_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1150118 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: o= rg.apache.solr.security.HadoopAuthPlugin [junit4] 2> 1150122 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.s.HadoopAuthPlugin Params: {proxyuser.anyHostUsersGro= up.hosts=3D*, proxyuser.wrongHost.hosts=3D1.1.1.1.1.1, proxyuser.bogusGroup= .groups=3D__some_bogus_group, proxyuser.localHostAnyGroup.groups=3D*, cooki= e.domain=3D127.0.0.1, proxyuser.wrongHost.groups=3D*, type=3Dsimple, proxyu= ser.noHosts.groups=3D*, proxyuser.anyHostUsersGroup.groups=3Dstaff, proxyus= er.noGroups.hosts=3D*, proxyuser.anyHostAnyUser.groups=3D*, delegation-toke= n.token-kind=3Dsolr-dt, proxyuser.bogusGroup.hosts=3D*, zk-dt-secret-manage= r.znodeWorkingPath=3Dsolr/security/zkdtsm, signer.secret.provider.zookeeper= .path=3D/token, zk-dt-secret-manager.enable=3Dtrue, proxyuser.localHostAnyG= roup.hosts=3Dserv1-vm3,localhost,127.0.0.1, proxyuser.anyHostAnyUser.hosts= =3D*, signer.secret.provider=3Dzookeeper} [junit4] 2> 1150126 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 1150129 INFO (jetty-launcher-1896-thread-1-EventThread) [= n:127.0.0.1:60841_solr ] o.a.c.f.s.ConnectionStateManager State change: = CONNECTED [junit4] 2> 1150174 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing i= nternode requests [junit4] 2> 1150196 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (1) [junit4] 2> 1150202 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1150205 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:57442_solr [junit4] 2> 1150206 INFO (zkCallback-1912-thread-1-processing-n:127.0= .0.1:60841_solr) [n:127.0.0.1:60841_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1150207 INFO (zkCallback-1914-thread-1-processing-n:127.0= .0.1:57442_solr) [n:127.0.0.1:57442_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1150238 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60841.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@41538633 [junit4] 2> 1150247 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60841.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@41538633 [junit4] 2> 1150247 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60841.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@41538633 [junit4] 2> 1150248 INFO (jetty-launcher-1896-thread-1) [n:127.0.0.1:= 60841_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/so= lr-core/test/J1/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_6= 64B7BE5FCE5DCCD-001/tempDir-001/node2/. [junit4] 2> 1150269 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: o= rg.apache.solr.security.HadoopAuthPlugin [junit4] 2> 1150269 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.s.HadoopAuthPlugin Params: {proxyuser.anyHostUsersGro= up.hosts=3D*, proxyuser.wrongHost.hosts=3D1.1.1.1.1.1, proxyuser.bogusGroup= .groups=3D__some_bogus_group, proxyuser.localHostAnyGroup.groups=3D*, cooki= e.domain=3D127.0.0.1, proxyuser.wrongHost.groups=3D*, type=3Dsimple, proxyu= ser.noHosts.groups=3D*, proxyuser.anyHostUsersGroup.groups=3Dstaff, proxyus= er.noGroups.hosts=3D*, proxyuser.anyHostAnyUser.groups=3D*, delegation-toke= n.token-kind=3Dsolr-dt, proxyuser.bogusGroup.hosts=3D*, zk-dt-secret-manage= r.znodeWorkingPath=3Dsolr/security/zkdtsm, signer.secret.provider.zookeeper= .path=3D/token, zk-dt-secret-manager.enable=3Dtrue, proxyuser.localHostAnyG= roup.hosts=3Dserv1-vm3,localhost,127.0.0.1, proxyuser.anyHostAnyUser.hosts= =3D*, signer.secret.provider=3Dzookeeper} [junit4] 2> 1150271 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 1150282 INFO (jetty-launcher-1896-thread-2-EventThread) [= n:127.0.0.1:57442_solr ] o.a.c.f.s.ConnectionStateManager State change: = CONNECTED [junit4] 2> 1150341 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing i= nternode requests [junit4] 2> 1150430 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57442.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@41538633 [junit4] 2> 1150437 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57442.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@41538633 [junit4] 2> 1150437 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57442.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@41538633 [junit4] 2> 1150438 INFO (jetty-launcher-1896-thread-2) [n:127.0.0.1:= 57442_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/so= lr-core/test/J1/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_6= 64B7BE5FCE5DCCD-001/tempDir-001/node1/. [junit4] 2> 1150503 INFO (zkConnectionManagerCallback-1921-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1150508 INFO (zkConnectionManagerCallback-1925-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1150510 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live node= s from ZooKeeper... (0) -> (2) [junit4] 2> 1150511 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider = Cluster at 127.0.0.1:65167/solr ready [junit4] 2> 1150549 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyInvalidProxyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ##= #Starting testProxyInvalidProxyUser [junit4] 2> 1150617 WARN (TEST-TestImpersonationWithHadoopAuth.testPr= oxyInvalidProxyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.ResponseProce= ssCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3Dbar&p=3Dba= r&t=3Dsimple-dt&e=3D1519321758842&s=3DzHHlHxYBCeb+VcWtQXBVkNMGRyg=3D"; Doma= in=3D127.0.0.1; Expires=3DKam, 22-Feb-2018 17:49:18 GMT; HttpOnly". Invalid= 'expires' attribute: Kam, 22-Feb-2018 17:49:18 GMT [junit4] 2> 1150617 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyInvalidProxyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ##= #Ending testProxyInvalidProxyUser [junit4] 2> 1150709 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyInvalidGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###Sta= rting testProxyInvalidGroup [junit4] 2> 1150807 WARN (qtp109810651-9847) [ ] o.a.h.s.ShellBase= dUnixGroupsMapping got exception trying to get groups for user bar: id: inv= alid user name: "bar" [junit4] 2> id: invalid user name: "bar" [junit4] 2>=20 [junit4] 2> 1150810 WARN (TEST-TestImpersonationWithHadoopAuth.testPr= oxyInvalidGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.ResponseProcessCo= okies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DbogusGroup&p= =3DbogusGroup&t=3Dsimple-dt&e=3D1519321758949&s=3D/JhY23TLC/zwhcJKAindSD1rY= RY=3D"; Domain=3D127.0.0.1; Expires=3DKam, 22-Feb-2018 17:49:18 GMT; HttpOn= ly". Invalid 'expires' attribute: Kam, 22-Feb-2018 17:49:18 GMT [junit4] 2> 1150811 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyInvalidGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###End= ing testProxyInvalidGroup [junit4] 2> 1150845 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyWrongHost-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###Starti= ng testProxyWrongHost [junit4] 2> 1165966 WARN (jetty-launcher-1896-thread-2-SendThread(127= .0.0.1:65167)) [n:127.0.0.1:57442_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 15532ms for sessionid 0x100a71607= 6a0006 [junit4] 2> 1165966 WARN (jetty-launcher-1896-thread-1-SendThread(127= .0.0.1:65167)) [n:127.0.0.1:60841_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 15594ms for sessionid 0x100a71607= 6a0007 [junit4] 2> 1165967 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessio= nid 0x100a716076a0006, likely client has closed socket [junit4] 2> 1165967 WARN (jetty-launcher-1896-thread-1-SendThread(127= .0.0.1:65167)) [n:127.0.0.1:60841_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 15665ms for sessionid 0x100a71607= 6a0004 [junit4] 2> 1165967 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessio= nid 0x100a716076a0007, likely client has closed socket [junit4] 2> 1165966 WARN (jetty-launcher-1896-thread-2-SendThread(127= .0.0.1:65167)) [n:127.0.0.1:57442_solr ] o.a.z.ClientCnxn Client session= timed out, have not heard from server in 15595ms for sessionid 0x100a71607= 6a0008 [junit4] 2> 1165967 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessio= nid 0x100a716076a0004, likely client has closed socket [junit4] 2> 1165968 WARN (TEST-TestImpersonationWithHadoopAuth.testPr= oxyWrongHost-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.ResponseProcessCooki= es Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DwrongHost&p=3Dwro= ngHost&t=3Dsimple-dt&e=3D1519321759075&s=3DngiTx5X0FbK3aQCAeNZIBdL8ykk=3D";= Domain=3D127.0.0.1; Expires=3DKam, 22-Feb-2018 17:49:19 GMT; HttpOnly". In= valid 'expires' attribute: Kam, 22-Feb-2018 17:49:19 GMT [junit4] 2> 1165968 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessio= nid 0x100a716076a0008, likely client has closed socket [junit4] 2> 1165968 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyWrongHost-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###Ending= testProxyWrongHost [junit4] 2> 1165995 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateHost-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###Sta= rting testProxyValidateHost [junit4] 2> 1165997 INFO (qtp109810651-9847) [n:127.0.0.1:60841_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with para= ms doAs=3Dbar&action=3DLIST&user.name=3DlocalHostAnyGroup&wt=3Djavabin&vers= ion=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 1165997 INFO (qtp109810651-9847) [n:127.0.0.1:60841_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections p= arams=3D{doAs=3Dbar&action=3DLIST&user.name=3DlocalHostAnyGroup&wt=3Djavabi= n&version=3D2} status=3D0 QTime=3D0 [junit4] 2> 1165998 WARN (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateHost-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.ResponseProcessCo= okies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DlocalHostAnyGr= oup&p=3DlocalHostAnyGroup&t=3Dsimple-dt&e=3D1519321774225&s=3DFOniuiMETM7Ro= mhNhPN8/RHowkw=3D"; Domain=3D127.0.0.1; Expires=3DKam, 22-Feb-2018 17:49:34= GMT; HttpOnly". Invalid 'expires' attribute: Kam, 22-Feb-2018 17:49:34 GMT [junit4] 2> 1165998 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateHost-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###End= ing testProxyValidateHost [junit4] 2> 1166019 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###St= arting testProxyValidateGroup [junit4] 2> 1166021 INFO (qtp109810651-9847) [n:127.0.0.1:60841_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with para= ms doAs=3Djenkins&action=3DLIST&user.name=3DanyHostUsersGroup&wt=3Djavabin&= version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 1166021 INFO (qtp109810651-9847) [n:127.0.0.1:60841_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections p= arams=3D{doAs=3Djenkins&action=3DLIST&user.name=3DanyHostUsersGroup&wt=3Dja= vabin&version=3D2} status=3D0 QTime=3D0 [junit4] 2> 1166022 WARN (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.ResponseProcessC= ookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DanyHostUsersG= roup&p=3DanyHostUsersGroup&t=3Dsimple-dt&e=3D1519321774249&s=3DHEVRyJ1DfHae= auYGC8pS5NtPsMQ=3D"; Domain=3D127.0.0.1; Expires=3DKam, 22-Feb-2018 17:49:3= 4 GMT; HttpOnly". Invalid 'expires' attribute: Kam, 22-Feb-2018 17:49:34 GM= T [junit4] 2> 1166022 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###En= ding testProxyValidateGroup [junit4] 2> 1166043 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyNoConfigGroups-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###S= tarting testProxyNoConfigGroups [junit4] 2> 1166047 WARN (TEST-TestImpersonationWithHadoopAuth.testPr= oxyNoConfigGroups-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.ResponseProcess= Cookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DnoGroups&p= =3DnoGroups&t=3Dsimple-dt&e=3D1519321774273&s=3DBQwWMtnuL5NKvY2JalbRvYz/Uns= =3D"; Domain=3D127.0.0.1; Expires=3DKam, 22-Feb-2018 17:49:34 GMT; HttpOnly= ". Invalid 'expires' attribute: Kam, 22-Feb-2018 17:49:34 GMT [junit4] 2> 1166047 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyNoConfigGroups-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###E= nding testProxyNoConfigGroups [junit4] IGNOR/A 0.00s J1 | TestImpersonationWithHadoopAuth.testForwardi= ng [junit4] > Assumption #1: 'awaitsfix' test group is disabled (@Awaits= Fix(bugUrl=3Dhttps://issues.apache.org/jira/browse/HADOOP-9893)) [junit4] 2> 1166067 WARN (zkConnectionManagerCallback-1915-thread-1-p= rocessing-n:127.0.0.1:57442_solr) [n:127.0.0.1:57442_solr ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f23= 0156 name: ZooKeeperConnection Watcher:127.0.0.1:65167/solr got event Watch= edEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 1166068 WARN (zkConnectionManagerCallback-1915-thread-1-p= rocessing-n:127.0.0.1:57442_solr) [n:127.0.0.1:57442_solr ] o.a.s.c.c.Co= nnectionManager zkClient has disconnected [junit4] 2> 1166070 WARN (zkConnectionManagerCallback-1913-thread-1-p= rocessing-n:127.0.0.1:60841_solr) [n:127.0.0.1:60841_solr ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1df4= f30d name: ZooKeeperConnection Watcher:127.0.0.1:65167/solr got event Watch= edEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 1166070 WARN (zkConnectionManagerCallback-1913-thread-1-p= rocessing-n:127.0.0.1:60841_solr) [n:127.0.0.1:60841_solr ] o.a.s.c.c.Co= nnectionManager zkClient has disconnected [junit4] 2> 1166072 WARN (zkConnectionManagerCallback-1925-thread-1) = [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.Con= nectionManager@4b946a48 name: ZooKeeperConnection Watcher:127.0.0.1:65167/s= olr got event WatchedEvent state:Disconnected type:None path:null path: nul= l type: None [junit4] 2> 1166072 WARN (zkConnectionManagerCallback-1925-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 1166074 INFO (jetty-launcher-1896-thread-2-EventThread) [= n:127.0.0.1:57442_solr ] o.a.c.f.s.ConnectionStateManager State change: = SUSPENDED [junit4] 2> 1166082 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyNullProxyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###St= arting testProxyNullProxyUser [junit4] 2> 1166084 INFO (qtp109810651-9847) [n:127.0.0.1:60841_solr = ] o.a.s.s.SolrDispatchFilter 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.hadoop.security.authentication.server.Pse= udoAuthenticationHandler.authenticate(PseudoAuthenticationHandler.java:195) [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.HadoopAuthFilter.doFilter(H= adoopAuthFilter.java:110) [junit4] 2> =09at org.apache.solr.security.HadoopAuthPlugin.doAuthenti= cate(HadoopAuthPlugin.java:245) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.authentic= ateRequest(SolrDispatchFilter.java:454) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:349) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:326) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:533) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:1595) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1253) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:168) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:473) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:1564) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:166) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1155) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.handl= e(ScopedHandler.java:141) [junit4] 2> =09at org.eclipse.jetty.server.handler.gzip.GzipHandler.ha= ndle(GzipHandler.java:527) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:132) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 30) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:347) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:256) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:279) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:102) [junit4] 2> =09at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEn= dPoint.java:124) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.doProduce(EatWhatYouKill.java:247) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.produce(EatWhatYouKill.java:140) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.run(EatWhatYouKill.java:131) [junit4] 2> =09at org.eclipse.jetty.util.thread.ReservedThreadExecutor= $ReservedThread.run(ReservedThreadExecutor.java:382) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:708) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:626) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 1166085 WARN (qtp109810651-9847) [n:127.0.0.1:60841_solr = ] o.e.j.s.HttpChannel /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:460) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:349) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:326) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:533) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:1595) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1253) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:168) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:473) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:1564) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:166) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1155) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.handl= e(ScopedHandler.java:141) [junit4] 2> =09at org.eclipse.jetty.server.handler.gzip.GzipHandler.ha= ndle(GzipHandler.java:527) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:132) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 30) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:347) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:256) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:279) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:102) [junit4] 2> =09at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEn= dPoint.java:124) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.doProduce(EatWhatYouKill.java:247) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.produce(EatWhatYouKill.java:140) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.run(EatWhatYouKill.java:131) [junit4] 2> =09at org.eclipse.jetty.util.thread.ReservedThreadExecutor= $ReservedThread.run(ReservedThreadExecutor.java:382) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:708) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:626) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: java.lang.IllegalArgumentException: userName is= NULL, empty or contains a '&' [junit4] 2> =09at org.apache.hadoop.security.authentication.util.AuthT= oken.checkForIllegalArgument(AuthToken.java:87) [junit4] 2> =09at org.apache.hadoop.security.authentication.util.AuthT= oken.(AuthToken.java:70) [junit4] 2> =09at org.apache.hadoop.security.authentication.server.Aut= henticationToken.(AuthenticationToken.java:58) [junit4] 2> =09at org.apache.hadoop.security.authentication.server.Pse= udoAuthenticationHandler.authenticate(PseudoAuthenticationHandler.java:195) [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.HadoopAuthFilter.doFilter(H= adoopAuthFilter.java:110) [junit4] 2> =09at org.apache.solr.security.HadoopAuthPlugin.doAuthenti= cate(HadoopAuthPlugin.java:245) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.authentic= ateRequest(SolrDispatchFilter.java:454) [junit4] 2> =09... 31 more [junit4] 2> 1166091 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyNullProxyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###En= ding testProxyNullProxyUser [junit4] 2> 1166116 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateAnyHostAnyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCas= eJ4 ###Starting testProxyValidateAnyHostAnyUser [junit4] 2> 1166118 INFO (qtp109810651-9843) [n:127.0.0.1:60841_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with para= ms doAs=3Dbar&action=3DLIST&user.name=3DanyHostAnyUser&wt=3Djavabin&version= =3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 1166118 INFO (qtp109810651-9843) [n:127.0.0.1:60841_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections p= arams=3D{doAs=3Dbar&action=3DLIST&user.name=3DanyHostAnyUser&wt=3Djavabin&v= ersion=3D2} status=3D0 QTime=3D0 [junit4] 2> 1166118 WARN (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateAnyHostAnyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.Respons= eProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DanyH= ostAnyUser&p=3DanyHostAnyUser&t=3Dsimple-dt&e=3D1519321774345&s=3DIccpRisAz= OxzK6Ul30CjJqsGR74=3D"; Domain=3D127.0.0.1; Expires=3DKam, 22-Feb-2018 17:4= 9:34 GMT; HttpOnly". Invalid 'expires' attribute: Kam, 22-Feb-2018 17:49:34= GMT [junit4] 2> 1166118 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyValidateAnyHostAnyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCas= eJ4 ###Ending testProxyValidateAnyHostAnyUser [junit4] 2> 1166142 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyNoConfigHosts-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###St= arting testProxyNoConfigHosts [junit4] 2> 1166146 WARN (TEST-TestImpersonationWithHadoopAuth.testPr= oxyNoConfigHosts-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.ResponseProcessC= ookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DnoHosts&p=3Dn= oHosts&t=3Dsimple-dt&e=3D1519321774372&s=3D55BeW3BTxVr8rdP1tQr3XTDJnUI=3D";= Domain=3D127.0.0.1; Expires=3DKam, 22-Feb-2018 17:49:34 GMT; HttpOnly". In= valid 'expires' attribute: Kam, 22-Feb-2018 17:49:34 GMT [junit4] 2> 1166146 INFO (TEST-TestImpersonationWithHadoopAuth.testPr= oxyNoConfigHosts-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 ###En= ding testProxyNoConfigHosts [junit4] 2> 1167142 INFO (jetty-closer-1897-thread-1) [ ] o.e.j.s.= AbstractConnector Stopped ServerConnector@fcffb5a{HTTP/1.1,[http/1.1]}{127.= 0.0.1:0} [junit4] 2> 1167142 INFO (jetty-closer-1897-thread-2) [ ] o.e.j.s.= AbstractConnector Stopped ServerConnector@2cf24888{HTTP/1.1,[http/1.1]}{127= .0.0.1:0} [junit4] 2> 1167143 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.c.= CoreContainer Shutting down CoreContainer instance=3D183697033 [junit4] 2> 1167143 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.c.= CoreContainer Shutting down CoreContainer instance=3D287571485 [junit4] 2> 1167143 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.node, tag=3D= null [junit4] 2> 1167143 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@4036d69f: rootName =3D solr_60841, domain =3D solr.node, service= url =3D null, agent id =3D null] for registry solr.node / com.codahale.met= rics.MetricRegistry@4f2914ab [junit4] 2> 1167143 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.node, tag=3D= null [junit4] 2> 1167143 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@2e2b551f: rootName =3D solr_57442, domain =3D solr.node, service= url =3D null, agent id =3D null] for registry solr.node / com.codahale.met= rics.MetricRegistry@7499b9fe [junit4] 2> 1167148 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jvm, tag=3Dn= ull [junit4] 2> 1167148 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@78991771: rootName =3D solr_60841, domain =3D solr.jvm, service = url =3D null, agent id =3D null] for registry solr.jvm / com.codahale.metri= cs.MetricRegistry@2b4611d4 [junit4] 2> 1167148 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jvm, tag=3Dn= ull [junit4] 2> 1167148 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@65b249a: rootName =3D solr_57442, domain =3D solr.jvm, service u= rl =3D null, agent id =3D null] for registry solr.jvm / com.codahale.metric= s.MetricRegistry@2b4611d4 [junit4] 2> 1167150 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag= =3Dnull [junit4] 2> 1167150 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@144e693c: rootName =3D solr_60841, domain =3D solr.jetty, servic= e url =3D null, agent id =3D null] for registry solr.jetty / com.codahale.m= etrics.MetricRegistry@94ee25a [junit4] 2> 1167150 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag= =3Dnull [junit4] 2> 1167151 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.m.= r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJ= mxReporter@5ca57bdc: rootName =3D solr_57442, domain =3D solr.jetty, servic= e url =3D null, agent id =3D null] for registry solr.jetty / com.codahale.m= etrics.MetricRegistry@94ee25a [junit4] 2> 1167244 INFO (zkConnectionManagerCallback-1913-thread-1-p= rocessing-n:127.0.0.1:60841_solr) [n:127.0.0.1:60841_solr ] o.a.s.c.c.Co= nnectionManager zkClient has connected [junit4] 2> 1167245 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.c.= ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:60841_s= olr [junit4] 2> 1167246 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag= =3Dnull [junit4] 2> 1167246 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.c.= TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 tra= nsient cores [junit4] 2> 1167246 INFO (jetty-closer-1897-thread-1) [ ] o.a.s.c.= Overseer Overseer (id=3D72241307093434372-127.0.0.1:60841_solr-n_0000000000= ) closing [junit4] 2> 1167247 INFO (OverseerStateUpdate-72241307093434372-127.0= .0.1:60841_solr-n_0000000000) [n:127.0.0.1:60841_solr ] o.a.s.c.Overseer= According to ZK I (id=3D72241307093434372-127.0.0.1:60841_solr-n_000000000= 0) am no longer a leader. [junit4] 2> 1167247 INFO (OverseerStateUpdate-72241307093434372-127.0= .0.1:60841_solr-n_0000000000) [n:127.0.0.1:60841_solr ] o.a.s.c.Overseer= Overseer Loop exiting : 127.0.0.1:60841_solr [junit4] 2> 1167248 INFO (OverseerCollectionConfigSetProcessor-722413= 07093434372-127.0.0.1:60841_solr-n_0000000000) [n:127.0.0.1:60841_solr ]= o.a.s.c.OverseerTaskProcessor According to ZK I (id=3D72241307093434372-12= 7.0.0.1:60841_solr-n_0000000000) am no longer a leader. [junit4] 2> 1167249 WARN (OverseerAutoScalingTriggerThread-7224130709= 3434372-127.0.0.1:60841_solr-n_0000000000) [n:127.0.0.1:60841_solr ] o.a= .s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are clos= ed, exiting. [junit4] 2> 1167250 INFO (zkCallback-1912-thread-5-processing-n:127.0= .0.1:60841_solr) [n:127.0.0.1:60841_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 1167250 WARN (ScheduledTrigger-3227-thread-1) [n:127.0.0.= 1:60841_solr ] o.a.s.c.a.TriggerBase Exception updating trigger state '/= autoscaling/triggerState/.auto_add_replicas' [junit4] 2> java.lang.InterruptedException: sleep interrupted [junit4] 2> =09at java.lang.Thread.sleep(Native Method) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryDela= y(ZkCmdExecutor.java:111) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:70) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.exists(Sol= rZkClient.java:316) [junit4] 2> =09at org.apache.solr.client.solrj.impl.ZkDistribStateMana= ger.hasData(ZkDistribStateManager.java:58) [junit4] 2> =09at org.apache.solr.cloud.autoscaling.TriggerBase.saveSt= ate(TriggerBase.java:203) [junit4] 2> =09at org.apache.solr.cloud.autoscaling.ScheduledTriggers$= ScheduledTrigger.run(ScheduledTriggers.java:573) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.runAndReset(FutureTa= sk.java:308) [junit4] 2> =09at java.util.concurrent.ScheduledThreadPoolExecutor$Sch= eduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [junit4] 2> =09at java.util.concurrent.ScheduledThreadPoolExecutor$Sch= eduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [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> 1167253 ERROR (Thread[Thread-3648,5,TGRP-TestImpersonation= WithHadoopAuth]) [n:127.0.0.1:60841_solr ] o.a.h.s.t.d.AbstractDelegatio= nTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedExcep= tion: sleep interrupted [junit4] 2> 1167612 INFO (jetty-closer-1897-thread-1) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@50ac8a59{/solr,null,= UNAVAILABLE} [junit4] 2> 1167613 INFO (jetty-closer-1897-thread-1) [ ] o.e.j.s.= session Stopped scavenging [junit4] 2> 1167820 INFO (zkConnectionManagerCallback-1915-thread-1-p= rocessing-n:127.0.0.1:57442_solr) [n:127.0.0.1:57442_solr ] o.a.s.c.c.Co= nnectionManager zkClient has connected [junit4] 2> 1167821 INFO (zkCallback-1914-thread-5-processing-n:127.0= .0.1:57442_solr) [n:127.0.0.1:57442_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 1167821 INFO (zkCallback-1914-thread-1-processing-n:127.0= .0.1:57442_solr) [n:127.0.0.1:57442_solr ] o.a.s.c.OverseerElectionConte= xt I am going to be the leader 127.0.0.1:57442_solr [junit4] 2> 1167822 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.c.= ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:57442_s= olr [junit4] 2> 1167823 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.m.= SolrMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag= =3Dnull [junit4] 2> 1167823 INFO (jetty-closer-1897-thread-2) [ ] o.a.s.c.= TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 tra= nsient cores [junit4] 2> 1167823 INFO (zkCallback-1914-thread-5-processing-n:127.0= .0.1:57442_solr) [n:127.0.0.1:57442_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 1167825 ERROR (Thread[Thread-3651,5,TGRP-TestImpersonation= WithHadoopAuth]) [n:127.0.0.1:57442_solr ] o.a.h.s.t.d.AbstractDelegatio= nTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedExcep= tion: sleep interrupted [junit4] 2> 1168055 INFO (jetty-closer-1897-thread-2) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2c4a5f35{/solr,null,= UNAVAILABLE} [junit4] 2> 1168056 INFO (jetty-closer-1897-thread-2) [ ] o.e.j.s.= session Stopped scavenging [junit4] 2> 1168057 ERROR (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler= is not registered, so ZooKeeper server won't take any action on ERROR or S= HUTDOWN server state changes [junit4] 2> 1168059 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.= 0.1:65167 65167 [junit4] 2> 1173159 INFO (Thread-3631) [ ] o.a.s.c.ZkTestServer co= nnecting to 127.0.0.1:65167 65167 [junit4] 2> 1173160 WARN (Thread-3631) [ ] 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> =093=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot [junit4] 2> =093=09/solr/clusterstate.json [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t/DK_1 [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t/DK_3 [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t/DK_2 [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t/DK_4 [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =093=09/solr/live_nodes [junit4] 2> =093=09/solr/collections [junit4] 2> =093=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot [junit4] 2> =092=09/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoo= t [junit4] 2>=20 [junit4] 2> 1173160 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#= [664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 --------------------= ----------------------------------- Done waiting for tracked resources to b= e released [junit4] 2> Feb 22, 2018 7:49:41 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thre= ad(s). [junit4] 2> Feb 22, 2018 7:50:01 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.sol= r.security.hadoop.TestImpersonationWithHadoopAuth:=20 [junit4] 2> 1) Thread[id=3D9893, name=3Djetty-launcher-1896-thread-= 2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestImpersonationWithHad= oopAuth] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] 2> at java.util.concurrent.CountDownLatch.await(Count= DownLatch.java:277) [junit4] 2> at org.apache.curator.CuratorZookeeperClient.inter= nalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(Retr= yLoop.java:105) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:279) [junit4] 2> at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:41) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue.readValue(SharedValue.java:244) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue.access$100(SharedValue.java:44) [junit4] 2> at org.apache.curator.framework.recipes.shared.Sha= redValue$1.process(SharedValue.java:61) [junit4] 2> at org.apache.curator.framework.imps.NamespaceWatc= her.process(NamespaceWatcher.java:67) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.pro= cessEvent(ClientCnxn.java:531) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:506) [junit4] 2> Feb 22, 2018 7:50:01 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=3D9893, name=3Djetty-launcher-1896-thread-= 2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestImpersonationWithHad= oopAuth] [junit4] 2> 1193258 ERROR (jetty-launcher-1896-thread-2-EventThread) [= n:127.0.0.1:57442_solr ] o.a.c.f.i.CuratorFrameworkImpl Watcher exceptio= n [junit4] 2> java.lang.InterruptedException [junit4] 2> =09at java.util.concurrent.locks.AbstractQueuedSynchronize= r.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039) [junit4] 2> =09at java.util.concurrent.locks.AbstractQueuedSynchronize= r.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] 2> =09at java.util.concurrent.CountDownLatch.await(CountDownL= atch.java:277) [junit4] 2> =09at org.apache.curator.CuratorZookeeperClient.internalBl= ockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] 2> =09at org.apache.curator.RetryLoop.callWithRetry(RetryLoop= .java:105) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .pathInForeground(GetDataBuilderImpl.java:288) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .forPath(GetDataBuilderImpl.java:279) [junit4] 2> =09at org.apache.curator.framework.imps.GetDataBuilderImpl= .forPath(GetDataBuilderImpl.java:41) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue.readValue(SharedValue.java:244) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue.access$100(SharedValue.java:44) [junit4] 2> =09at org.apache.curator.framework.recipes.shared.SharedVa= lue$1.process(SharedValue.java:61) [junit4] 2> =09at org.apache.curator.framework.imps.NamespaceWatcher.p= rocess(NamespaceWatcher.java:67) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.processE= vent(ClientCnxn.java:531) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:506) [junit4] 2> Feb 22, 2018 7:50:01 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene70): {}, do= cValues:{}, maxPointsInLeafNode=3D1203, maxMBSortInHeap=3D5.987098023249644= , sim=3DRandomSimilarity(queryNorm=3Dtrue): {}, locale=3Did, timezone=3DUS/= Aleutian [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bi= t)/cpus=3D3,threads=3D1,free=3D30673680,total=3D427352064 [junit4] 2> NOTE: All tests run in this JVM: [JsonLoaderTest, TestCros= sCoreJoin, TestSubQueryTransformer, TestCoreDiscovery, TestDynamicFieldColl= ectionResource, TestWordDelimiterFilterFactory, TestOverriddenPrefixQueryFo= rCustomFieldType, TestDirectoryFactory, UpdateParamsTest, TestRandomDVFacet= ing, TestRTGBase, TestManagedSynonymGraphFilterFactory, TestSizeLimitedDist= ributedMap, DistanceUnitsTest, TestIndexingPerformance, TestExactStatsCache= , TestDFRSimilarityFactory, BlobRepositoryCloudTest, HdfsRecoveryZkTest, Re= plicaListTransformerTest, CoreSorterTest, TestLegacyFieldReuse, TestQuerySe= nderListener, VMParamsZkACLAndCredentialsProvidersTest, TestPayloadScoreQPa= rserPlugin, UninvertDocValuesMergePolicyTest, TestCloudInspectUtil, TestFas= tWriter, URLClassifyProcessorTest, TestCSVLoader, FullSolrCloudDistribCmdsT= est, TestInPlaceUpdatesStandalone, SubstringBytesRefFilterTest, EchoParamsT= est, DeleteLastCustomShardedReplicaTest, TestSha256AuthenticationProvider, = SchemaApiFailureTest, TestTlogReplica, TestConfigSetsAPIExclusivity, Sugges= terWFSTTest, TestIntervalFaceting, FieldAnalysisRequestHandlerTest, ZkState= WriterTest, TestReloadDeadlock, TestDocSet, TestFieldSortValues, TestSimple= QParserPlugin, HdfsChaosMonkeyNothingIsSafeTest, AutoAddReplicasPlanActionT= est, DOMUtilTest, SuggesterTest, ChaosMonkeySafeLeaderWithPullReplicasTest,= SuggestComponentTest, TestSolrFieldCacheBean, TestFieldCacheSort, TestColl= apseQParserPlugin, TestMacroExpander, RuleEngineTest, CheckHdfsIndexTest, N= odeLostTriggerTest, DocExpirationUpdateProcessorFactoryTest, BooleanFieldTe= st, AnalyticsQueryTest, MetricTriggerTest, AddBlockUpdateTest, Classificati= onUpdateProcessorFactoryTest, DocValuesTest, SolrCoreCheckLockOnStartupTest= , TestConfigSetProperties, TestJettySolrRunner, MigrateRouteKeyTest, TestCo= nfigsApi, V2ApiIntegrationTest, OverseerTest, TestReRankQParserPlugin, Test= NamedUpdateProcessors, TestStressRecovery, TestNumericTokenStream, SolrSlf4= jReporterTest, BlockJoinFacetRandomTest, SolrCmdDistributorTest, Collection= StateFormat2Test, TestRTimerTree, TestAtomicUpdateErrorCases, TermVectorCom= ponentTest, PrimUtilsTest, ResponseHeaderTest, TestSolrCloudWithDelegationT= okens, CdcrVersionReplicationTest, TestFieldTypeCollectionResource, SpellPo= ssibilityIteratorTest, TestExportWriter, ComputePlanActionTest, CircularLis= tTest, HdfsAutoAddReplicasIntegrationTest, TestSolrCoreProperties, TestLega= cyFieldCache, TestImpersonationWithHadoopAuth] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestImpersona= tionWithHadoopAuth -Dtests.seed=3D664B7BE5FCE5DCCD -Dtests.slow=3Dtrue -Dte= sts.locale=3Did -Dtests.timezone=3DUS/Aleutian -Dtests.asserts=3Dtrue -Dtes= ts.file.encoding=3DUTF-8 [junit4] ERROR 0.00s J1 | TestImpersonationWithHadoopAuth (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLea= kError: 1 thread leaked from SUITE scope at org.apache.solr.security.hadoop= .TestImpersonationWithHadoopAuth:=20 [junit4] > 1) Thread[id=3D9893, name=3Djetty-launcher-1896-thread-= 2-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestImpersonationWithHad= oopAuth] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] > at java.util.concurrent.CountDownLatch.await(Count= DownLatch.java:277) [junit4] > at org.apache.curator.CuratorZookeeperClient.inter= nalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] > at org.apache.curator.RetryLoop.callWithRetry(Retr= yLoop.java:105) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:279) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:41) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue.readValue(SharedValue.java:244) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue.access$100(SharedValue.java:44) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue$1.process(SharedValue.java:61) [junit4] > at org.apache.curator.framework.imps.NamespaceWatc= her.process(NamespaceWatcher.java:67) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.pro= cessEvent(ClientCnxn.java:531) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:506) [junit4] > =09at __randomizedtesting.SeedInfo.seed([664B7BE5FCE5DCCD]= :0) [junit4] Completed [223/777 (1!)] on J1 in 43.51s, 10 tests, 1 error, 1 = skipped <<< FAILURES! [...truncated 1116 lines...] [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithSecureImpersonati= on [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-So= lr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWi= thSecureImpersonation_664B7BE5FCE5DCCD-001/init-core-data-001 [junit4] 2> 3500155 WARN (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSe= archers: numOpens=3D12 numCloses=3D12 [junit4] 2> 3500156 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieField= s (NUMERIC_POINTS_SYSPROP=3Dfalse) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 3500157 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl = (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reaso= n=3D, value=3DNaN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 3500157 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[664B7BE5FCE5DCCD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sa= nity checks: test.solr.allowed.securerandom=3Dnull & java.security.egd=3Dfi= le:/dev/./urandom [junit4] 2> 3500186 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Startin= g cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-So= laris/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithSecureI= mpersonation_664B7BE5FCE5DCCD-001/tempDir-001 [junit4] 2> 3500186 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TES= T SERVER [junit4] 2> 3500187 INFO (Thread-9060) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3500187 INFO (Thread-9060) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 3500188 ERROR (Thread-9060) [ ] 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> 3500287 INFO (SUITE-TestSolrCloudWithSecureImpersonation-= seed#[664B7BE5FCE5DCCD]-worker) [ ] o.a.s.c.ZkTestServer start zk server= on port:44552 [junit4] 2> 3500289 INFO (zkConnectionManagerCallback-8337-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3500294 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.= s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00,= git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3500294 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.= s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00,= git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3500313 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 3500313 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 3500313 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.= s.session Scavenging every 600000ms [junit4] 2> 3500313 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 3500313 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 3500313 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.= s.session Scavenging every 600000ms [junit4] 2> 3500313 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@75efc18{/solr,null= ,AVAILABLE} [junit4] 2> 3500313 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4e70b88d{/solr,nul= l,AVAILABLE} [junit4] 2> 3500314 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@47983774{SSL,[ssl, http/1.1]}{1= 27.0.0.1:58744} [junit4] 2> 3500314 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.= s.Server Started @3506852ms [junit4] 2> 3500314 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D58= 744} [junit4] 2> 3500314 ERROR (jetty-launcher-8334-thread-1) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 3500314 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 7.3.0 [junit4] 2> 3500314 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3500314 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3500314 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-22T08:28:28.660Z [junit4] 2> 3500315 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@2a89fbb6{SSL,[ssl, http/1.1]}{1= 27.0.0.1:45966} [junit4] 2> 3500315 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.= s.Server Started @3506854ms [junit4] 2> 3500315 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D45= 966} [junit4] 2> 3500316 ERROR (jetty-launcher-8334-thread-2) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 3500316 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 7.3.0 [junit4] 2> 3500316 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3500316 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3500316 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-22T08:28:28.662Z [junit4] 2> 3500318 INFO (zkConnectionManagerCallback-8339-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3500318 INFO (zkConnectionManagerCallback-8341-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3500318 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3500318 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3500327 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:44552/solr [junit4] 2> 3500327 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:44552/solr [junit4] 2> 3500328 INFO (zkConnectionManagerCallback-8349-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3500329 INFO (zkConnectionManagerCallback-8347-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3500331 INFO (zkConnectionManagerCallback-8352-thread-1-p= rocessing-n:127.0.0.1:58744_solr) [n:127.0.0.1:58744_solr ] o.a.s.c.c.Co= nnectionManager zkClient has connected [junit4] 2> 3500332 INFO (zkConnectionManagerCallback-8353-thread-1-p= rocessing-n:127.0.0.1:45966_solr) [n:127.0.0.1:45966_solr ] o.a.s.c.c.Co= nnectionManager zkClient has connected [junit4] 2> 3500439 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 3500440 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader= 127.0.0.1:45966_solr [junit4] 2> 3500441 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.c.Overseer Overseer (id=3D72241461333852166-127.0.0.1= :45966_solr-n_0000000000) starting [junit4] 2> 3500450 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:45966_solr [junit4] 2> 3500455 INFO (OverseerStateUpdate-72241461333852166-127.0= .0.1:45966_solr-n_0000000000) [n:127.0.0.1:45966_solr ] o.a.s.c.c.ZkStat= eReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3500516 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: o= rg.apache.solr.security.HttpParamDelegationTokenPlugin [junit4] 2> 3500525 WARN (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_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> 3500533 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_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> 3500543 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (1) [junit4] 2> 3500579 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 3500585 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:58744_solr [junit4] 2> 3500587 INFO (zkCallback-8351-thread-1-processing-n:127.0= .0.1:45966_solr) [n:127.0.0.1:45966_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3500588 INFO (zkCallback-8350-thread-1-processing-n:127.0= .0.1:58744_solr) [n:127.0.0.1:58744_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3500613 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 3500629 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: o= rg.apache.solr.security.HttpParamDelegationTokenPlugin [junit4] 2> 3500629 WARN (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_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> 3500630 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_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> 3500644 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 3500670 INFO (jetty-launcher-8334-thread-1-EventThread) [= n:127.0.0.1:58744_solr ] o.a.c.f.s.ConnectionStateManager State change: = CONNECTED [junit4] 2> 3500670 INFO (jetty-launcher-8334-thread-2-EventThread) [= n:127.0.0.1:45966_solr ] o.a.c.f.s.ConnectionStateManager State change: = CONNECTED [junit4] 2> 3501793 WARN (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_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> 3501794 WARN (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_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> 3501839 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45966.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@6329caff [junit4] 2> 3501844 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58744.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@6329caff [junit4] 2> 3501848 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45966.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@6329caff [junit4] 2> 3501849 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45966.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@6329caff [junit4] 2> 3501850 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:= 45966_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/so= lr-core/test/J0/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_664B7B= E5FCE5DCCD-001/tempDir-001/node2/. [junit4] 2> 3501854 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58744.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@6329caff [junit4] 2> 3501854 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58744.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@6329caff [junit4] 2> 3501855 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:= 58744_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/so= lr-core/test/J0/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_664B7B= E5FCE5DCCD-001/tempDir-001/node1/. [junit4] 2> 3501903 INFO (zkConnectionManagerCallback-8359-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3501932 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 #= ##Starting testProxyInvalidGroup [junit4] 2> 3502002 WARN (qtp1999082171-35861) [ ] o.a.h.s.ShellBa= sedUnixGroupsMapping got exception trying to get groups for user bar: id: i= nvalid user name: "bar" [junit4] 2> id: invalid user name: "bar" [junit4] 2>=20 [junit4] 2> 3502021 WARN (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.h.c.p.ResponseProc= essCookies Invalid cookie header: "Set-Cookie: hadoop.auth=3D"u=3DbogusGrou= p&p=3DbogusGroup&t=3Dtest&e=3D1519324110314&s=3Dm2HrKJsFIwmZA/sERLK5XPJaUis= =3D"; Path=3D/; Domain=3D127.0.0.1; Expires=3D , 22- -2018 18:28:30 GMT; = Secure; HttpOnly". Invalid 'expires' attribute: , 22- -2018 18:28:30 GMT [junit4] 2> 3502021 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyInvalidGroup-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 #= ##Ending testProxyInvalidGroup [junit4] 2> 3502046 INFO (TEST-TestSolrCloudWithSecureImpersonation.t= estProxyNullProxyUser-seed#[664B7BE5FCE5DCCD]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testProxyNullProxyUser [junit4] 2> 3502046 INFO (qtp1999082171-35864) [ ] o.a.s.s.SolrDis= patchFilter Error authenticating [junit4] 2> java.lang.IllegalArgumentException: userName is NULL, empt= y or contains a '&' [junit4] 2> =09at org.apache.hadoop.security.authentication.util.AuthT= oken.checkForIllegalArgument(AuthToken.java:87) [junit4] 2> =09at org.apache.hadoop.security.authentication.util.AuthT= oken.(AuthToken.java:70) [junit4] 2> =09at org.apache.hadoop.security.authentication.server.Aut= henticationToken.(AuthenticationToken.java:58) [junit4] 2> =09at org.apache.solr.security.HttpParamDelegationTokenPlu= gin$HttpParamDelegationTokenAuthenticationHandler$HttpParamAuthenticationHa= ndler.authenticate(HttpParamDelegationTokenPlugin.java:213) [junit4] 2> =09at org.apache.hadoop.security.token.delegation.web.Dele= gationTokenAuthenticationHandler.authenticate(DelegationTokenAuthentication= Handler.java:348) [junit4] 2> =09at org.apache.solr.security.RequestContinuesRecorderAut= henticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandl= er.java:69) [junit4] 2> =09at org.apache.hadoop.security.authentication.server.Aut= henticationFilter.doFilter(AuthenticationFilter.java:518) [junit4] 2> =09at org.apache.solr.security.DelegationTokenKerberosFilt= er.doFilter(DelegationTokenKerberosFilter.java:134) [junit4] 2> =09at org.apache.solr.security.HttpParamDelegationTokenPlu= gin$HttpParamToRequestFilter.doFilter(HttpParamDelegationTokenPlugin.java:2= 45) [junit4] 2> =09at org.apache.solr.security.KerberosPlugin.doAuthentica= te(KerberosPlugin.java:243) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.authentic= ateRequest(SolrDispatchFilter.java:454) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:349) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:326) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:533) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:1595) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1253) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:168) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:473) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:1564) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:166) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1155) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.handl= e(ScopedHandler.java:141) [junit4] 2> =09at org.eclipse.jetty.server.handler.gzip.GzipHandler.ha= ndle(GzipHandler.java:527) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:132) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 30) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:347) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:256) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:279) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:102) [junit4] 2> =09at org.eclipse.jetty.io.ssl.SslConnection.onFillable(Ss= lConnection.java:289) [junit4] 2> =09at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(S= slConnection.java:149) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:102) [junit4] 2> =09at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEn= dPoint.java:124) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.doProduce(EatWhatYouKill.java:247) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.produce(EatWhatYouKill.java:140) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.EatWhatYouKil= l.run(EatWhatYouKill.java:131) [junit4] 2> =09at org.eclipse.jetty.util.thread.ReservedThreadExecutor= $ReservedThread.run(ReservedThreadExecutor.java:382) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:708) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:626) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3502048 WARN (qtp1999082171-35864) [ ] o.e.j.s.HttpCha= nnel /solr/admin/collections [junit4] 2> org.apache.solr.common.SolrException: Error during request= authentication,=20 [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.authentic= ateRequest(SolrDispatchFilter.java:460) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:349) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:326) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1637) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:533) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:1595) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextH= andle(ScopedHandler.java:188) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1253) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:168) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:473) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:1564) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.nextS= cope(ScopedHandler.java:166) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1155) [junit4] 2> =09at org.eclipse.jetty.server.handler [...truncated too long message...] org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher= .java:67) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.processE= vent(ClientCnxn.java:531) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:506) [junit4] 2> Feb 22, 2018 8:29:15 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=3D30019, maxDocsPerChunk=3D129, block= Size=3D224), termVectorsFormat=3DCompressingTermVectorsFormat(compressionMo= de=3DFAST_DECOMPRESSION, chunkSize=3D30019, blockSize=3D224)), sim=3DRandom= Similarity(queryNorm=3Dtrue): {}, locale=3Dar-MA, timezone=3DAmerica/Argent= ina/Catamarca [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bi= t)/cpus=3D3,threads=3D1,free=3D258459320,total=3D518979584 [junit4] 2> NOTE: All tests run in this JVM: [NumericFieldsTest, Chang= edSchemaMergeTest, TestFieldCacheSortRandom, TestCustomStream, TestTriggerI= ntegration, UpdateLogTest, TestBM25SimilarityFactory, SolrIndexConfigTest, = TestTolerantSearch, TestTrieFacet, TestGenericDistributedQueue, DistribCurs= orPagingTest, TestRebalanceLeaders, TestSortByMinMaxFunction, StatelessScri= ptUpdateProcessorFactoryTest, TestPolicyCloud, TestSearcherReuse, Distribut= edQueryElevationComponentTest, TestSystemCollAutoCreate, TestSurroundQueryP= arser, SolrCLIZkUtilsTest, TestJsonRequest, TestConfigSetsAPI, TestRawRespo= nseWriter, XmlUpdateRequestHandlerTest, TestExactSharedStatsCache, TestFilt= ering, ZkFailoverTest, UUIDUpdateProcessorFallbackTest, TestMinMaxOnMultiVa= luedField, TestFieldResource, TestMultiValuedNumericRangeQuery, DirectoryFa= ctoryTest, OverseerRolesTest, TestXIncludeConfig, HttpSolrCallGetCoreTest, = TestIBSimilarityFactory, BitVectorTest, ImplicitSnitchTest, HdfsDirectoryTe= st, TestPayloadCheckQParserPlugin, CopyFieldTest, TestCopyFieldCollectionRe= source, TestFaceting, OpenExchangeRatesOrgProviderTest, TestCustomDocTransf= ormer, TestSolrCloudSnapshots, DistributedSuggestComponentTest, TestUninver= tingReader, SuggestComponentContextFilterQueryTest, CollectionTooManyReplic= asTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestHashQParserPlugin, Te= stAddFieldRealTimeGet, RankQueryTest, LeaderElectionContextKeyTest, TestDis= tributedStatsComponentCardinality, TestStandardQParsers, TestAnalyzeInfixSu= ggestions, BasicDistributedZkTest, TestUtils, TestCollectionAPI, AddSchemaF= ieldsUpdateProcessorFactoryTest, TestNumericRangeQuery64, CreateCollectionC= leanupTest, BasicZkTest, DistributedFacetExistsSmallTest, TestDeleteCollect= ionOnDownNodes, HdfsThreadLeakTest, TestShortCircuitedRequests, TestRuleBas= edAuthorizationPlugin, TestQueryUtils, TestLegacyNumericUtils, CustomCollec= tionTest, TestLeaderElectionZkExpiry, TestUtilizeNode, StressHdfsTest, Test= ConfigSetsAPIZkFailure, TestCSVResponseWriter, TestSuggestSpellingConverter= , FastVectorHighlighterTest, TestCollectionAPIs, TestBinaryField, CdcrBidir= ectionalTest, StatsReloadRaceTest, PolyFieldTest, AnalysisAfterCoreReloadTe= st, TestNoOpRegenerator, TestSmileRequest, SolrMetricManagerTest, TestLegac= yNumericRangeQueryBuilder, DateRangeFieldTest, DirectSolrSpellCheckerTest, = TriLevelCompositeIdRoutingTest, TestSchemalessBufferedUpdates, LegacyCloudC= lusterPropTest, TestLegacyField, OpenCloseCoreStressTest, TestTrackingShard= HandlerFactory, SpatialFilterTest, BlockDirectoryTest, HLLUtilTest, TestHal= fAndHalfDocValues, RegexBytesRefFilterTest, SolrRequestParserTest, TestShar= dHandlerFactory, HighlighterMaxOffsetTest, ClassificationUpdateProcessorInt= egrationTest, TestFilteredDocIdSet, DataDrivenBlockJoinTest, AutoScalingHan= dlerTest, SolrJmxReporterTest, TestReload, ConvertedLegacyTest, CdcrRequest= HandlerTest, TestSolrConfigHandler, TestConfigSetImmutable, SolrGraphiteRep= orterTest, TestInPlaceUpdatesDistrib, LargeFieldTest, TestOmitPositions, Di= rectSolrConnectionTest, PreAnalyzedUpdateProcessorTest, SecurityConfHandler= Test, RollingRestartTest, TestCursorMarkWithoutUniqueKey, TestRecoveryHdfs,= TestJoin, TestGroupingSearch, TestQuerySenderNoQuery, SuggesterFSTTest, An= alyticsMergeStrategyTest, SolrMetricsIntegrationTest, AtomicUpdatesTest, Te= stSSLRandomization, TestStressCloudBlindAtomicUpdates, PathHierarchyTokeniz= erFactoryTest, ZkNodePropsTest, CacheHeaderTest, AddReplicaTest, Conjunctio= nSolrSpellCheckerTest, TestEmbeddedSolrServerSchemaAPI, V2StandaloneTest, T= estHighlightDedupGrouping, TestDynamicFieldResource, TestExceedMaxTermLengt= h, StatsComponentTest, TestCloudDeleteByQuery, TestMissingGroups, HdfsUnloa= dDistributedZkTest, CurrencyRangeFacetCloudTest, IgnoreCommitOptimizeUpdate= ProcessorFactoryTest, SpellingQueryConverterTest, TestElisionMultitermQuery= , FullHLLTest, RequiredFieldsTest, TestJavabinTupleStreamParser, ConfigureR= ecoveryStrategyTest, ResourceLoaderTest, TestRandomFlRTGCloud, QueryElevati= onComponentTest, TestSweetSpotSimilarityFactory, TestObjectReleaseTracker, = BasicAuthStandaloneTest, TestClusterStateProvider, TestRecovery, TestSchema= VersionResource, TestQueryTypes, TestDynamicLoading, CoreAdminCreateDiscove= rTest, WordBreakSolrSpellCheckerTest, TestSolrConfigHandlerCloud, TestOnRec= onnectListenerSupport, TestJmxIntegration, TestPseudoReturnFields, TestClou= dPseudoReturnFields, TestGeoJSONResponseWriter, MultiTermTest, SolrInfoBean= Test, TestStressLiveNodes, LeaderElectionTest, TermsComponentTest, TestQuer= yWrapperFilter, TestFastOutputStream, TestEmbeddedSolrServerAdminHandler, B= asicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, LeaderElect= ionIntegrationTest, ShardRoutingTest, RecoveryZkTest, TestReplicationHandle= r, ClusterStateUpdateTest, TestRandomFaceting, TestZkChroot, ZkCLITest, Sha= rdRoutingCustomTest, TestDistributedSearch, DistributedSpellCheckComponentT= est, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet,= TestStressReorder, TestStressVersions, DistributedTermsComponentTest, Test= RangeQuery, TestCoreContainer, TestSolr4Spatial, SolrCoreTest, TestSort, Te= stFunctionQuery, TestLazyCores, DirectUpdateHandlerTest, TestIndexSearcher,= HighlighterTest, SoftAutoCommitTest, TestLFUCache, TestSolrIndexConfig, Ba= dComponentTest, TestMergePolicyConfig, OutputWriterTest, TestLuceneMatchVer= sion, TestCharFilters, TestLMJelinekMercerSimilarityFactory, TestFastLRUCac= he, PreAnalyzedFieldTest, ClusterStateTest, SliceStateTest, UUIDFieldTest, = CursorPagingTest, SolrTestCaseJ4Test, TestDistributedMissingSort, TestLucen= eIndexBackCompat, ActionThrottleTest, AliasIntegrationTest, CleanupOldIndex= Test, CloudExitableDirectoryReaderTest, CollectionsAPISolrJTest, CreateRout= edAliasTest, DeleteReplicaTest, DistributedVersionInfoTest, HttpPartitionOn= CommitTest, HttpPartitionTest, LIRRollingUpdatesTest, LeaderInitiatedRecove= ryOnShardRestartTest, MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTe= st, MoveReplicaTest, MultiThreadedOCPTest, RecoveryAfterSoftCommitTest, Rep= laceNodeNoTargetTest, RestartWhileUpdatingTest, SharedFSAutoReplicaFailover= Test, SolrCloudExampleTest, TestCloudRecovery, TestClusterProperties, TestC= ryptoKeys, TestDownShardTolerantSearch, TestLeaderElectionWithEmptyReplica,= TestPrepRecovery, TestSegmentSorting, TestSkipOverseerOperations, TestSolr= CloudWithSecureImpersonation] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestSolrCloud= WithSecureImpersonation -Dtests.seed=3D664B7BE5FCE5DCCD -Dtests.slow=3Dtrue= -Dtests.locale=3Dar-MA -Dtests.timezone=3DAmerica/Argentina/Catamarca -Dte= sts.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 0.00s J0 | TestSolrCloudWithSecureImpersonation (suite)= <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLea= kError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.TestSolrC= loudWithSecureImpersonation:=20 [junit4] > 1) Thread[id=3D35909, name=3Djetty-launcher-8334-thread= -1-EventThread, state=3DTIMED_WAITING, group=3DTGRP-TestSolrCloudWithSecure= Impersonation] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNano= s(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchr= onizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] > at java.util.concurrent.CountDownLatch.await(Count= DownLatch.java:277) [junit4] > at org.apache.curator.CuratorZookeeperClient.inter= nalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] > at org.apache.curator.RetryLoop.callWithRetry(Retr= yLoop.java:105) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:279) [junit4] > at org.apache.curator.framework.imps.GetDataBuilde= rImpl.forPath(GetDataBuilderImpl.java:41) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue.readValue(SharedValue.java:244) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue.access$100(SharedValue.java:44) [junit4] > at org.apache.curator.framework.recipes.shared.Sha= redValue$1.process(SharedValue.java:61) [junit4] > at org.apache.curator.framework.imps.NamespaceWatc= her.process(NamespaceWatcher.java:67) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.pro= cessEvent(ClientCnxn.java:531) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run= (ClientCnxn.java:506) [junit4] > =09at __randomizedtesting.SeedInfo.seed([664B7BE5FCE5DCCD]= :0) [junit4] Completed [578/777 (2!)] on J0 in 47.33s, 12 tests, 1 error <<<= FAILURES! [...truncated 49362 lines...] ------=_Part_16_1550822611.1519290559902 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_16_1550822611.1519290559902--