Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25152/
Java: 64bit/jdk-14-ea+24 -XX:-UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenRenew

Error Message:
expected:<200> but was:<403>

Stack Trace:
java.lang.AssertionError: expected:<200> but was:<403>
        at 
__randomizedtesting.SeedInfo.seed([E7DE86309870294B:D045722EA0BCF4EF]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.failNotEquals(Assert.java:834)
        at org.junit.Assert.assertEquals(Assert.java:645)
        at org.junit.Assert.assertEquals(Assert.java:631)
        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.renewDelegationToken(TestSolrCloudWithDelegationTokens.java:138)
        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.verifyDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:343)
        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:361)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:833)




Build Log:
[...truncated 14785 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens
   [junit4]   2> 1639451 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1639451 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_E7DE86309870294B-001/data-dir-182-001
   [junit4]   2> 1639451 WARN  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=36 numCloses=36
   [junit4]   2> 1639451 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1639452 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1639454 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_E7DE86309870294B-001/tempDir-001
   [junit4]   2> 1639454 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1639455 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1639455 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1639555 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.ZkTestServer start zk server on port:46801
   [junit4]   2> 1639555 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:46801
   [junit4]   2> 1639555 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46801
   [junit4]   2> 1639555 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46801
   [junit4]   2> 1639556 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1639558 INFO  (zkConnectionManagerCallback-11395-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1639558 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1639575 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1639587 INFO  (zkConnectionManagerCallback-11397-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1639587 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1639587 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1639599 INFO  (zkConnectionManagerCallback-11399-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1639599 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1639715 WARN  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1639716 WARN  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1639716 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1639716 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1639716 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: 
afcf563148970e98786327af5e07c261fda175d3; jvm 14-ea+24-1107
   [junit4]   2> 1639717 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1639717 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1639717 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: 
afcf563148970e98786327af5e07c261fda175d3; jvm 14-ea+24-1107
   [junit4]   2> 1639744 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1639744 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1639745 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1639745 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@29f09e7{/solr,null,AVAILABLE}
   [junit4]   2> 1639745 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1639745 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1639745 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1639745 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@19fffbd4{/solr,null,AVAILABLE}
   [junit4]   2> 1639745 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@753292ff{ssl,[ssl, alpn, 
http/1.1, h2]}{127.0.0.1:36165}
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.s.Server Started @1639780ms
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=36165}
   [junit4]   2> 1639746 ERROR (jetty-launcher-11400-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@2de1c7b8{ssl,[ssl, alpn, 
http/1.1, h2]}{127.0.0.1:34437}
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.s.Server Started @1639780ms
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-12-13T16:32:23.785288Z
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34437}
   [junit4]   2> 1639746 ERROR (jetty-launcher-11400-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1639746 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-12-13T16:32:23.785451Z
   [junit4]   2> 1639751 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1639751 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1639752 INFO  (zkConnectionManagerCallback-11402-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1639752 INFO  (zkConnectionManagerCallback-11404-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1639752 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1639752 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1639752 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1639752 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1639975 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1639976 WARN  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@664333ba[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1639976 WARN  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@664333ba[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1639979 WARN  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4b97da4f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1639979 WARN  (jetty-launcher-11400-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4b97da4f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1639980 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46801/solr
   [junit4]   2> 1639995 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1639999 INFO  (zkConnectionManagerCallback-11412-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1639999 INFO  (jetty-launcher-11400-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1640033 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1640034 WARN  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@702b1893[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1640034 WARN  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@702b1893[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1640037 WARN  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2b1fa11[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1640037 WARN  (jetty-launcher-11400-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@2b1fa11[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1640038 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46801/solr
   [junit4]   2> 1640040 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1640041 INFO  (zkConnectionManagerCallback-11418-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1640041 INFO  (jetty-launcher-11400-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1640100 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1640103 INFO  (zkConnectionManagerCallback-11420-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1640103 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1640196 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:34437_solr
   [junit4]   2> 1640197 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.Overseer Overseer 
(id=72177547546132487-127.0.0.1:34437_solr-n_0000000000) starting
   [junit4]   2> 1640201 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1640203 INFO  (zkConnectionManagerCallback-11424-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1640206 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1640207 INFO  
(OverseerStateUpdate-72177547546132487-127.0.0.1:34437_solr-n_0000000000) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:34437_solr
   [junit4]   2> 1640239 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34437_solr
   [junit4]   2> 1640247 INFO  (zkCallback-11419-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1640248 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.CoreContainer Initializing authentication 
plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
   [junit4]   2> 1640253 WARN  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.s.i.Krb5HttpClientBuilder 
org.apache.solr.client.solrj.impl.Krb5HttpClientBuilder is configured without 
specifying system property 'java.security.auth.login.config'
   [junit4]   2> 1640255 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.s.KerberosPlugin Params: {token.valid=30, 
delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/, 
zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, 
token.validity=36000, signer.secret.provider.zookeeper.path=/token, 
type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT, 
signer.secret.provider=zookeeper, 
delegation-token.json-mapper.AUTO_CLOSE_TARGET=false}
   [junit4]   2> 1640259 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.c.f.i.CuratorFrameworkImpl Starting
   [junit4]   2> 1640261 INFO  (zkCallback-11423-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1640262 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:36165_solr as DOWN
   [junit4]   2> 1640263 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1640263 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36165_solr
   [junit4]   2> 1640264 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.CoreContainer Initializing authentication 
plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
   [junit4]   2> 1640265 WARN  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.s.i.Krb5HttpClientBuilder 
org.apache.solr.client.solrj.impl.Krb5HttpClientBuilder is configured without 
specifying system property 'java.security.auth.login.config'
   [junit4]   2> 1640265 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.s.KerberosPlugin Params: {token.valid=30, 
delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/, 
zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, 
token.validity=36000, signer.secret.provider.zookeeper.path=/token, 
type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT, 
signer.secret.provider=zookeeper, 
delegation-token.json-mapper.AUTO_CLOSE_TARGET=false}
   [junit4]   2> 1640265 INFO  (zkCallback-11423-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1640265 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.c.f.i.CuratorFrameworkImpl Starting
   [junit4]   2> 1640300 INFO  (jetty-launcher-11400-thread-2-EventThread) [    
 ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
   [junit4]   2> 1640301 INFO  (jetty-launcher-11400-thread-1-EventThread) [    
 ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
   [junit4]   2> 1640302 INFO  (zkCallback-11419-thread-2) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1640319 WARN  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.s.i.Krb5HttpClientBuilder 
org.apache.solr.client.solrj.impl.Krb5HttpClientBuilder is configured without 
specifying system property 'java.security.auth.login.config'
   [junit4]   2> 1640320 WARN  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.CoreContainer Not all security plugins 
configured!  authentication=enabled authorization=disabled.  Solr is only as 
secure as you make it. Consider configuring authentication/authorization before 
exposing Solr to users internal or external.  See 
https://s.apache.org/solrsecurity for more info
   [junit4]   2> 1640324 WARN  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.s.i.Krb5HttpClientBuilder 
org.apache.solr.client.solrj.impl.Krb5HttpClientBuilder is configured without 
specifying system property 'java.security.auth.login.config'
   [junit4]   2> 1640324 WARN  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.CoreContainer Not all security plugins 
configured!  authentication=enabled authorization=disabled.  Solr is only as 
secure as you make it. Consider configuring authentication/authorization before 
exposing Solr to users internal or external.  See 
https://s.apache.org/solrsecurity for more info
   [junit4]   2> 1640329 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1640340 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1640356 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36165.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@293039b2
   [junit4]   2> 1640357 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34437.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@293039b2
   [junit4]   2> 1640365 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36165.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@293039b2
   [junit4]   2> 1640365 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36165.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@293039b2
   [junit4]   2> 1640365 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34437.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@293039b2
   [junit4]   2> 1640365 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34437.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@293039b2
   [junit4]   2> 1640366 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1640366 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1640367 INFO  (jetty-launcher-11400-thread-1) 
[n:127.0.0.1:36165_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_E7DE86309870294B-001/tempDir-001/node1/.
   [junit4]   2> 1640367 INFO  (jetty-launcher-11400-thread-2) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_E7DE86309870294B-001/tempDir-001/node2/.
   [junit4]   2> 1640424 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 1640425 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1640426 INFO  (zkConnectionManagerCallback-11435-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1640426 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1640427 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1640428 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46801/solr ready
   [junit4]   2> 1640456 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
   [junit4]   2> 1640545 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr     
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params 
action=LIST&delegation=GgADYmFyA2JhcgCKAW8AGqe_igFvJCcrvwEEFH-we3-ZpSdQgZRnRSDX4eI9-vC1B3NvbHItZHQA&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1640545 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=LIST&delegation=GgADYmFyA2JhcgCKAW8AGqe_igFvJCcrvwEEFH-we3-ZpSdQgZRnRSDX4eI9-vC1B3NvbHItZHQA&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1640547 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
   [junit4]   2> 1640581 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
   [junit4]   2> 1640589 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1640590 INFO  (zkConnectionManagerCallback-11440-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1640590 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1640591 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1640592 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:46801/solr ready
   [junit4]   2> 1640606 INFO  (qtp1645948848-34016) [n:127.0.0.1:34437_solr    
 ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params 
action=LIST&delegation=GgADYmFyA2JhcgCKAW8AGqgsigFvJCcsLAIEFEDeWOo5gmhhDRlXtLJd8gWgZF0iB3NvbHItZHQA&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1640606 INFO  (qtp1645948848-34016) [n:127.0.0.1:34437_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=LIST&delegation=GgADYmFyA2JhcgCKAW8AGqgsigFvJCcsLAIEFEDeWOo5gmhhDRlXtLJd8gWgZF0iB3NvbHItZHQA&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1640710 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1640711 INFO  (zkConnectionManagerCallback-11445-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1640711 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1640712 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1640712 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:46801/solr ready
   [junit4]   2> 1640848 INFO  (qtp1645948848-34017) [n:127.0.0.1:34437_solr    
 ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params 
action=LIST&delegation=GgADYmFyA2JhcgCKAW8AGqgsigFvJCcsLAIEFEDeWOo5gmhhDRlXtLJd8gWgZF0iB3NvbHItZHQA&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1640848 INFO  (qtp1645948848-34017) [n:127.0.0.1:34437_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=LIST&delegation=GgADYmFyA2JhcgCKAW8AGqgsigFvJCcsLAIEFEDeWOo5gmhhDRlXtLJd8gWgZF0iB3NvbHItZHQA&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1640849 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1640850 INFO  (zkConnectionManagerCallback-11450-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1640850 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1640851 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1640852 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:46801/solr ready
   [junit4]   2> 1640964 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
   [junit4]   2> 1640988 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
   [junit4]   2> 1640993 WARN  (qtp986214893-34011) [     ] 
o.a.h.s.a.s.AuthenticationFilter Authentication exception: 
org.apache.hadoop.security.AccessControlException: bar is trying to renew a 
token (solr-dt owner=bar, renewer=bar, realUser=, issueDate=1576254745028, 
maxDate=1576859545028, sequenceNumber=3, masterKeyId=4) with wrong password
   [junit4]   2> 1640993 WARN  (qtp986214893-34011) [     ] 
o.a.s.s.KerberosPlugin Could not find 
org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1640995 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSolrCloudWithDelegationTokens 
-Dtests.method=testDelegationTokenRenew -Dtests.seed=E7DE86309870294B 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sq-XK 
-Dtests.timezone=Asia/Tokyo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.03s J1 | 
TestSolrCloudWithDelegationTokens.testDelegationTokenRenew <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<200> but 
was:<403>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E7DE86309870294B:D045722EA0BCF4EF]:0)
   [junit4]    >        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.renewDelegationToken(TestSolrCloudWithDelegationTokens.java:138)
   [junit4]    >        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.verifyDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:343)
   [junit4]    >        at 
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:361)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:833)
   [junit4]   2> 1641030 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
   [junit4]   2> 1641044 WARN  (qtp986214893-34009) [n:127.0.0.1:36165_solr     
] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1641050 WARN  (qtp986214893-34013) [     ] 
o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1641069 ERROR (qtp986214893-34009) [n:127.0.0.1:36165_solr     
] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path 
[/ZKDTSMTokensRoot/DT_4]
   [junit4]   2> 1641076 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1641077 INFO  (zkConnectionManagerCallback-11455-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1641077 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1641077 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[E7DE86309870294B])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1641079 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[E7DE86309870294B])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:46801/solr ready
   [junit4]   2> 1641204 WARN  (qtp986214893-34009) [n:127.0.0.1:36165_solr     
] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1641224 ERROR (qtp1645948848-34017) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path 
[/ZKDTSMTokensRoot/DT_5]
   [junit4]   2> 1641258 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
   [junit4]   2> 1641287 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
   [junit4]   2> 1641302 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr     
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params 
op=someSolrOperation&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1641302 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={op=someSolrOperation&action=LIST&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1641304 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
   [junit4]   2> 1641329 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClientWithUpdateRequests-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting 
testDelegationTokenSolrClientWithUpdateRequests
   [junit4]   2> 1641329 WARN  (qtp986214893-34011) [n:127.0.0.1:36165_solr     
] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1641343 INFO  (qtp986214893-34013) [n:127.0.0.1:36165_solr     
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
pullReplicas=0&name=testDelegationTokensWithUpdate&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&delegation=GgADYmFyA2JhcgCKAW8AGqsZigFvJCcvGQYEFBFqfcRXx6h_NtkpS256uwmnYRG_B3NvbHItZHQA&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1641345 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testDelegationTokensWithUpdate
   [junit4]   2> 1641345 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/protwords.txt
   [junit4]   2> 1641347 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/managed-schema
   [junit4]   2> 1641348 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/solrconfig.xml
   [junit4]   2> 1641349 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_it.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/contractions_it.txt
   [junit4]   2> 1641351 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stemdict_nl.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stemdict_nl.txt
   [junit4]   2> 1641352 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hy.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_hy.txt
   [junit4]   2> 1641354 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_no.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_no.txt
   [junit4]   2> 1641355 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_ca.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/contractions_ca.txt
   [junit4]   2> 1641357 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_id.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_id.txt
   [junit4]   2> 1641358 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_et.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_et.txt
   [junit4]   2> 1641359 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ga.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_ga.txt
   [junit4]   2> 1641360 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hi.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_hi.txt
   [junit4]   2> 1641362 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_da.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_da.txt
   [junit4]   2> 1641363 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ja.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_ja.txt
   [junit4]   2> 1641364 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_pt.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_pt.txt
   [junit4]   2> 1641364 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hu.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_hu.txt
   [junit4]   2> 1641366 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_el.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_el.txt
   [junit4]   2> 1641367 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ru.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_ru.txt
   [junit4]   2> 1641368 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_tr.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_tr.txt
   [junit4]   2> 1641368 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ar.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_ar.txt
   [junit4]   2> 1641369 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/userdict_ja.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/userdict_ja.txt
   [junit4]   2> 1641370 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_eu.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_eu.txt
   [junit4]   2> 1641371 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_it.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_it.txt
   [junit4]   2> 1641372 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_cz.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_cz.txt
   [junit4]   2> 1641372 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stoptags_ja.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stoptags_ja.txt
   [junit4]   2> 1641374 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_fr.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/contractions_fr.txt
   [junit4]   2> 1641375 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_de.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_de.txt
   [junit4]   2> 1641376 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fa.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_fa.txt
   [junit4]   2> 1641377 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_ga.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/contractions_ga.txt
   [junit4]   2> 1641378 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ca.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_ca.txt
   [junit4]   2> 1641379 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_nl.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_nl.txt
   [junit4]   2> 1641381 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_bg.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_bg.txt
   [junit4]   2> 1641383 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_lv.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_lv.txt
   [junit4]   2> 1641384 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_en.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_en.txt
   [junit4]   2> 1641386 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_es.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_es.txt
   [junit4]   2> 1641387 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_th.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_th.txt
   [junit4]   2> 1641388 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ro.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_ro.txt
   [junit4]   2> 1641389 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fr.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_fr.txt
   [junit4]   2> 1641390 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_sv.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_sv.txt
   [junit4]   2> 1641391 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fi.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_fi.txt
   [junit4]   2> 1641393 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/hyphenations_ga.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/hyphenations_ga.txt
   [junit4]   2> 1641394 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_gl.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/lang/stopwords_gl.txt
   [junit4]   2> 1641396 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/synonyms.txt
   [junit4]   2> 1641396 INFO  
(OverseerThreadFactory-11083-thread-1-processing-n:127.0.0.1:34437_solr) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/stopwords.txt
   [junit4]   2> 1641502 INFO  
(OverseerStateUpdate-72177547546132487-127.0.0.1:34437_solr-n_0000000000) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testDelegationTokensWithUpdate",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testDelegationTokensWithUpdate_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:36165/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1641725 INFO  (qtp1645948848-34014) [n:127.0.0.1:34437_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key 
params={omitHeader=true&wt=json} status=0 QTime=0
   [junit4]   2> 1641725 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr     
] o.a.s.s.PKIAuthenticationPlugin New Key obtained from  node: 
127.0.0.1:34437_solr / 
MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAmVlXNjzL31uhdx6qN51qggvmbAcNbVpdV2GFPbvJFjw1uhRZgw5UJ6XaWByOY6RQuC2D+gLXlLRkzxEXNlFJutec4BaE03F+3dxjD5M83yx8XoVqyH2AuCwNyQvozDkVn7loLs7iR+w2LSs9SO3zl85ajuM25v0AuOafZG2aSVHzO9kQuZY+TCvUKh9OzbUej+Q+Lke+XoQdTqfX8XOEqh8FaECKoOybHpxIJfCtb313T8CZskvYtmWQv6UMhrQUX4tdr8TTB1Wx90S0wehyMXn7GmnfBvYbpgWIDhUtsq1s0asMUWG3AKxLoksNiUgGz+PwHW8uML6DJovYZnEWFwIDAQAB
   [junit4]   2> 1641726 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr    
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=testDelegationTokensWithUpdate.AUTOCREATED&newCollection=true&name=testDelegationTokensWithUpdate_shard1_replica_n1&action=CREATE&numShards=1&collection=testDelegationTokensWithUpdate&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1642734 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 1642758 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.s.IndexSchema 
[testDelegationTokensWithUpdate_shard1_replica_n1] Schema name=default-config
   [junit4]   2> 1642886 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded 
schema default-config/1.6 with uniqueid field id
   [junit4]   2> 1642886 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.CoreContainer 
Creating SolrCore 'testDelegationTokensWithUpdate_shard1_replica_n1' using 
configuration from collection testDelegationTokensWithUpdate, trusted=true
   [junit4]   2> 1642886 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter 
JMX monitoring for 
'solr_36165.solr.core.testDelegationTokensWithUpdate.shard1.replica_n1' 
(registry 'solr.core.testDelegationTokensWithUpdate.shard1.replica_n1') enabled 
at server: com.sun.jmx.mbeanserver.JmxMBeanServer@293039b2
   [junit4]   2> 1642886 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[testDelegationTokensWithUpdate_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_E7DE86309870294B-001/tempDir-001/node1/testDelegationTokensWithUpdate_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_E7DE86309870294B-001/tempDir-001/node1/./testDelegationTokensWithUpdate_shard1_replica_n1/data/]
   [junit4]   2> 1642941 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1642941 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1642942 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard 
AutoCommit: if uncommitted for 15000ms; 
   [junit4]   2> 1642942 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1642943 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@54b239ce[testDelegationTokensWithUpdate_shard1_replica_n1] 
main]
   [junit4]   2> 1642944 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/testDelegationTokensWithUpdate.AUTOCREATED
   [junit4]   2> 1642945 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/testDelegationTokensWithUpdate.AUTOCREATED
   [junit4]   2> 1642945 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/testDelegationTokensWithUpdate.AUTOCREATED/managed-schema
   [junit4]   2> 1642945 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 1642945 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1642945 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.s.DirectSolrSpellChecker init: {name=default, field=_text_, 
classname=solr.DirectSolrSpellChecker, distanceMeasure=internal, accuracy=0.5, 
maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, 
maxQueryFrequency=0.01}
   [junit4]   2> 1642946 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 1642946 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1652822897574543360
   [junit4]   2> 1642948 INFO  
(searcherExecutor-11092-thread-1-processing-n:127.0.0.1:36165_solr 
x:testDelegationTokensWithUpdate_shard1_replica_n1 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to 
Searcher@54b239ce[testDelegationTokensWithUpdate_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1642948 INFO  
(searcherExecutor-11092-thread-1-processing-n:127.0.0.1:36165_solr 
x:testDelegationTokensWithUpdate_shard1_replica_n1 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1642948 INFO  
(searcherExecutor-11092-thread-1-processing-n:127.0.0.1:36165_solr 
x:testDelegationTokensWithUpdate_shard1_replica_n1 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1642949 INFO  
(searcherExecutor-11092-thread-1-processing-n:127.0.0.1:36165_solr 
x:testDelegationTokensWithUpdate_shard1_replica_n1 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.SolrCore 
[testDelegationTokensWithUpdate_shard1_replica_n1] Registered new searcher 
Searcher@54b239ce[testDelegationTokensWithUpdate_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1642950 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.ZkShardTerms 
Successful update of terms at 
/collections/testDelegationTokensWithUpdate/terms/shard1 to 
Terms{values={core_node2=0}, version=0}
   [junit4]   2> 1642950 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/testDelegationTokensWithUpdate/leaders/shard1
   [junit4]   2> 1642952 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1642952 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1642952 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync 
replicas to 
https://127.0.0.1:36165/solr/testDelegationTokensWithUpdate_shard1_replica_n1/
   [junit4]   2> 1642953 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 1642953 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.SyncStrategy 
https://127.0.0.1:36165/solr/testDelegationTokensWithUpdate_shard1_replica_n1/ 
has no replicas
   [junit4]   2> 1642953 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/testDelegationTokensWithUpdate/leaders/shard1/leader after winning 
as 
/collections/testDelegationTokensWithUpdate/leader_elect/shard1/election/72177547546132488-core_node2-n_0000000000
   [junit4]   2> 1642955 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:36165/solr/testDelegationTokensWithUpdate_shard1_replica_n1/ 
shard1
   [junit4]   2> 1643059 INFO  (zkCallback-11423-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testDelegationTokensWithUpdate/state.json] for collection 
[testDelegationTokensWithUpdate] has occurred - updating... (live nodes size: 
[2])
   [junit4]   2> 1643060 INFO  (zkCallback-11423-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testDelegationTokensWithUpdate/state.json] for collection 
[testDelegationTokensWithUpdate] has occurred - updating... (live nodes size: 
[2])
   [junit4]   2> 1643060 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.ZkController I am 
the leader, no recovery necessary
   [junit4]   2> 1643064 INFO  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testDelegationTokensWithUpdate.AUTOCREATED&newCollection=true&name=testDelegationTokensWithUpdate_shard1_replica_n1&action=CREATE&numShards=1&collection=testDelegationTokensWithUpdate&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1338
   [junit4]   2> 1643066 INFO  (qtp986214893-34013) [n:127.0.0.1:36165_solr     
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
45 seconds. Check all shard replicas
   [junit4]   2> 1643163 INFO  (zkCallback-11423-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testDelegationTokensWithUpdate/state.json] for collection 
[testDelegationTokensWithUpdate] has occurred - updating... (live nodes size: 
[2])
   [junit4]   2> 1643163 INFO  (zkCallback-11423-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testDelegationTokensWithUpdate/state.json] for collection 
[testDelegationTokensWithUpdate] has occurred - updating... (live nodes size: 
[2])
   [junit4]   2> 1643163 INFO  (zkCallback-11423-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testDelegationTokensWithUpdate/state.json] for collection 
[testDelegationTokensWithUpdate] has occurred - updating... (live nodes size: 
[2])
   [junit4]   2> 1643163 INFO  (qtp986214893-34013) [n:127.0.0.1:36165_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={pullReplicas=0&name=testDelegationTokensWithUpdate&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&delegation=GgADYmFyA2JhcgCKAW8AGqsZigFvJCcvGQYEFBFqfcRXx6h_NtkpS256uwmnYRG_B3NvbHItZHQA&wt=javabin&version=2}
 status=0 QTime=1820
   [junit4]   2> 1643173 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1652822897812570112,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1643173 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@59da7b44 
commitCommandVersion:1652822897812570112
   [junit4]   2> 1643175 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@38d15232[testDelegationTokensWithUpdate_shard1_replica_n1] 
main]
   [junit4]   2> 1643176 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1643176 INFO  
(searcherExecutor-11092-thread-1-processing-n:127.0.0.1:36165_solr 
x:testDelegationTokensWithUpdate_shard1_replica_n1 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to 
Searcher@38d15232[testDelegationTokensWithUpdate_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.vm.version=14-ea+24-1107,
 java.version=14-ea, timestamp=1576254747213, java.vendor=Oracle Corporation, 
os=Linux, os.version=5.0.0-36-generic, java.runtime.version=14-ea+24-1107, 
os.arch=amd64, source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1643176 INFO  
(searcherExecutor-11092-thread-1-processing-n:127.0.0.1:36165_solr 
x:testDelegationTokensWithUpdate_shard1_replica_n1 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1643176 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.ZkShardTerms 
Successful update of terms at 
/collections/testDelegationTokensWithUpdate/terms/shard1 to 
Terms{values={core_node2=1}, version=1}
   [junit4]   2> 1643176 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory 
[testDelegationTokensWithUpdate_shard1_replica_n1]  webapp=/solr path=/update 
params={waitSearcher=false&commit=true&softCommit=false&delegation=GgADYmFyA2JhcgCKAW8AGqsZigFvJCcvGQYEFBFqfcRXx6h_NtkpS256uwmnYRG_B3NvbHItZHQA&wt=javabin&version=2}{add=[dummy_id
 (1652822897811521536)], commit=} 0 11
   [junit4]   2> 1643178 INFO  
(searcherExecutor-11092-thread-1-processing-n:127.0.0.1:36165_solr 
x:testDelegationTokensWithUpdate_shard1_replica_n1 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.SolrCore 
[testDelegationTokensWithUpdate_shard1_replica_n1] Registered new searcher 
Searcher@38d15232[testDelegationTokensWithUpdate_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.vm.version=14-ea+24-1107,
 java.version=14-ea, timestamp=1576254747213, java.vendor=Oracle Corporation, 
os=Linux, os.version=5.0.0-36-generic, java.runtime.version=14-ea+24-1107, 
os.arch=amd64, source=flush, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1643186 INFO  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory 
[testDelegationTokensWithUpdate_shard1_replica_n1]  webapp=/solr path=/update 
params={delegation=GgADYmFyA2JhcgCKAW8AGqsZigFvJCcvGQYEFBFqfcRXx6h_NtkpS256uwmnYRG_B3NvbHItZHQA&wt=javabin&version=2}{add=[dummy_id
 (1652822897825153024)]} 0 8
   [junit4]   2> 1643188 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClientWithUpdateRequests-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending 
testDelegationTokenSolrClientWithUpdateRequests
   [junit4]   2> 1643236 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[E7DE86309870294B]) 
[     ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
   [junit4]   2> 1643237 WARN  (qtp986214893-34013) [n:127.0.0.1:36165_solr     
] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643258 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[E7DE86309870294B]) 
[     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1643279 INFO  (zkConnectionManagerCallback-11458-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1643279 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[E7DE86309870294B]) 
[     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1643354 INFO  
(OverseerCollectionConfigSetProcessor-72177547546132487-127.0.0.1:34437_solr-n_0000000000)
 [n:127.0.0.1:34437_solr     ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1643380 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[E7DE86309870294B]) 
[     ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
   [junit4]   2> 1643438 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
   [junit4]   2> 1643438 WARN  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643441 WARN  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643441 WARN  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter Authentication exception: 
org.apache.hadoop.security.AccessControlException: foo tries to renew a token 
(solr-dt owner=bar, renewer=bar, realUser=, issueDate=1576254747478, 
maxDate=1576859547478, sequenceNumber=8, masterKeyId=4) with non-matching 
renewer bar
   [junit4]   2> 1643442 WARN  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.s.KerberosPlugin 
Could not find org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1643443 WARN  (qtp1645948848-34016) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: 
org.apache.hadoop.security.AccessControlException: foo tries to renew a token 
(solr-dt owner=bar, renewer=bar, realUser=, issueDate=1576254747478, 
maxDate=1576859547478, sequenceNumber=8, masterKeyId=4) with non-matching 
renewer bar
   [junit4]   2> 1643443 WARN  (qtp1645948848-34016) [n:127.0.0.1:34437_solr    
 ] o.a.s.s.KerberosPlugin Could not find 
org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1643447 WARN  (qtp986214893-34013) [n:127.0.0.1:36165_solr     
] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643460 WARN  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643460 WARN  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter Authentication exception: 
org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew 
a token (solr-dt owner=bar, renewer=renewUser, realUser=, 
issueDate=1576254747486, maxDate=1576859547486, sequenceNumber=9, 
masterKeyId=4) with non-matching renewer renewUser
   [junit4]   2> 1643460 WARN  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.s.KerberosPlugin 
Could not find org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1643463 WARN  (qtp1645948848-34017) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643463 WARN  (qtp1645948848-34017) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: 
org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew 
a token (solr-dt owner=bar, renewer=renewUser, realUser=, 
issueDate=1576254747486, maxDate=1576859547486, sequenceNumber=9, 
masterKeyId=4) with non-matching renewer renewUser
   [junit4]   2> 1643463 WARN  (qtp1645948848-34017) [n:127.0.0.1:34437_solr    
 ] o.a.s.s.KerberosPlugin Could not find 
org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1643464 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
   [junit4]   2> 1643534 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
   [junit4]   2> 1643535 WARN  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643536 WARN  (qtp986214893-34013) [n:127.0.0.1:36165_solr     
] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643540 WARN  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643547 WARN  (qtp1645948848-34014) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643547 ERROR (qtp1645948848-34014) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path 
[/ZKDTSMTokensRoot/DT_10]
   [junit4]   2> 1643555 WARN  (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643555 ERROR (qtp986214893-34009) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path 
[/ZKDTSMTokensRoot/DT_10]
   [junit4]   2> 1643558 WARN  (qtp986214893-34013) [n:127.0.0.1:36165_solr     
] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643566 WARN  (qtp1645948848-34016) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643574 WARN  (qtp1645948848-34017) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643575 ERROR (qtp1645948848-34017) [n:127.0.0.1:34437_solr    
 ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path 
[/ZKDTSMTokensRoot/DT_11]
   [junit4]   2> 1643576 WARN  (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized 
access
   [junit4]   2> 1643577 ERROR (qtp986214893-34011) [n:127.0.0.1:36165_solr 
c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] 
o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path 
[/ZKDTSMTokensRoot/DT_11]
   [junit4]   2> 1643578 INFO  
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[E7DE86309870294B])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
   [junit4]   2> 1643690 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=731004308
   [junit4]   2> 1643690 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36165_solr
   [junit4]   2> 1643691 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1344629900
   [junit4]   2> 1643691 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34437_solr
   [junit4]   2> 1643695 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 1643695 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 1643695 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.c.ZkController Publish node=127.0.0.1:34437_solr as DOWN
   [junit4]   2> 1643695 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.c.ZkController Publish node=127.0.0.1:36165_solr as DOWN
   [junit4]   2> 1643726 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1643739 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1643739 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@47651846: rootName = 
solr_34437, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@496b2ee1
   [junit4]   2> 1643745 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1643745 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5e702158: rootName = 
solr_34437, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@c4d0e09
   [junit4]   2> 1643747 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1643747 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@371473da: rootName = 
solr_34437, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@19aa9ee1
   [junit4]   2> 1643748 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1643748 INFO  (coreCloseExecutor-11098-thread-1) 
[n:127.0.0.1:36165_solr c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.c.SolrCore 
[testDelegationTokensWithUpdate_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3c4bb7dc
   [junit4]   2> 1643748 INFO  (coreCloseExecutor-11098-thread-1) 
[n:127.0.0.1:36165_solr c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.m.SolrMetricManager 
Closing metric reporters for 
registry=solr.core.testDelegationTokensWithUpdate.shard1.replica_n1, 
tag=SolrCore@3c4bb7dc
   [junit4]   2> 1643748 INFO  (coreCloseExecutor-11098-thread-1) 
[n:127.0.0.1:36165_solr c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter 
Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@200d137: 
rootName = solr_36165, domain = 
solr.core.testDelegationTokensWithUpdate.shard1.replica_n1, service url = null, 
agent id = null] for registry 
solr.core.testDelegationTokensWithUpdate.shard1.replica_n1 / 
com.codahale.metrics.MetricRegistry@7dc0a2b1
   [junit4]   2> 1643761 INFO  (coreCloseExecutor-11098-thread-1) 
[n:127.0.0.1:36165_solr c:testDelegationTokensWithUpdate s:shard1 r:core_node2 
x:testDelegationTokensWithUpdate_shard1_replica_n1 ] o.a.s.m.SolrMetricManager 
Closing metric reporters for 
registry=solr.collection.testDelegationTokensWithUpdate.shard1.leader, 
tag=SolrCore@3c4bb7dc
   [junit4]   2> 1643763 INFO  (closeThreadPool-11466-thread-2) [     ] 
o.a.s.c.Overseer Overseer 
(id=72177547546132487-127.0.0.1:34437_solr-n_0000000000) closing
   [junit4]   2> 1643763 INFO  
(OverseerStateUpdate-72177547546132487-127.0.0.1:34437_solr-n_0000000000) 
[n:127.0.0.1:34437_solr     ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:34437_solr
   [junit4]   2> 1643763 INFO  
(OverseerAutoScalingTriggerThread-72177547546132487-127.0.0.1:34437_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we 
are closed, exiting.
   [junit4]   2> 1643784 INFO  (closeThreadPool-11466-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=72177547546132487-127.0.0.1:34437_solr-n_0000000000) closing
   [junit4]   2> 1643785 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1643785 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1b0b3c5c: rootName = 
solr_36165, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@6bd8e75d
   [junit4]   2> 1643791 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1643791 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@58b388af: rootName = 
solr_36165, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@c4d0e09
   [junit4]   2> 1643794 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1643794 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@53677211: rootName = 
solr_36165, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@19aa9ee1
   [junit4]   2> 1643794 INFO  (jetty-closer-11459-thread-1) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1643868 INFO  (jetty-closer-11459-thread-2) [     ] 
o.a.s.c.Overseer Overseer 
(id=72177547546132487-127.0.0.1:34437_solr-n_0000000000) closing
   [junit4]   2> 1643868 INFO  (zkCallback-11423-thread-2) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 1643868 ERROR 
(Thread[Thread-5292,5,TGRP-TestSolrCloudWithDelegationTokens]) [     ] 
o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received 
java.lang.InterruptedException: sleep interrupted
   [junit4]   2> 1643869 INFO  (Curator-Framework-0) [     ] 
o.a.c.f.i.CuratorFrameworkImpl backgroundOperationsLoop exiting
   [junit4]   2> 1643975 ERROR 
(Thread[Thread-5290,5,TGRP-TestSolrCloudWithDelegationTokens]) [     ] 
o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received 
java.lang.InterruptedException:

[...truncated too long message...]

ove limit:
   [junit4]   2> 
   [junit4]   2>        7       /solr/aliases.json
   [junit4]   2>        3       
/solr/collections/testDelegationTokensWithUpdate/terms/shard1
   [junit4]   2>        2       /solr/packages.json
   [junit4]   2>        2       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        24      
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
   [junit4]   2>        11      /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
   [junit4]   2>        9       
/solr/collections/testDelegationTokensWithUpdate/state.json
   [junit4]   2>        7       /solr/clusterprops.json
   [junit4]   2>        7       /solr/clusterstate.json
   [junit4]   2>        4       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
   [junit4]   2>        4       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
   [junit4]   2>        3       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_7
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_6
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_9
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_8
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_1
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_3
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_2
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_5
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_11
   [junit4]   2>        2       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        32      
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
   [junit4]   2>        13      /solr/overseer/queue
   [junit4]   2>        12      /solr/live_nodes
   [junit4]   2>        10      /solr/collections
   [junit4]   2>        8       
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
   [junit4]   2>        4       /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 1644374 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:46801
   [junit4]   2> 1644374 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46801
   [junit4]   2> 1644374 INFO  
(SUITE-TestSolrCloudWithDelegationTokens-seed#[E7DE86309870294B]-worker) [     
] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46801
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_E7DE86309870294B-001
   [junit4]   2> Dec 13, 2019 4:32:28 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): {}, 
docValues:{}, maxPointsInLeafNode=1203, maxMBSortInHeap=7.016813194272148, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1da8401f),
 locale=sq-XK, timezone=Asia/Tokyo
   [junit4]   2> NOTE: Linux 5.0.0-36-generic amd64/Oracle Corporation 14-ea 
(64-bit)/cpus=8,threads=1,free=129375520,total=517996544
   [junit4]   2> NOTE: All tests run in this JVM: [CSVRequestHandlerTest, 
CoreAdminRequestStatusTest, CdcrVersionReplicationTest, 
ChaosMonkeyNothingIsSafeTest, TestWordDelimiterFilterFactory, 
TestAnalyzeInfixSuggestions, RAMDirectoryFactoryTest, 
DistributedVersionInfoTest, UUIDUpdateProcessorFallbackTest, 
SimpleCollectionCreateDeleteTest, RandomizedTaggerTest, 
TestLeaderElectionZkExpiry, TestComplexPhraseQParserPlugin, 
PhrasesIdentificationComponentTest, BaseCdcrDistributedZkTest, 
XsltUpdateRequestHandlerTest, DistributedQueryComponentCustomSortTest, 
UUIDFieldTest, SplitHandlerTest, V2ApiIntegrationTest, TestLegacyFieldCache, 
CdcrUpdateLogTest, TestCloudRecovery2, 
TestPerFieldSimilarityWithDefaultOverride, TestScoreJoinQPScore, 
DisMaxRequestHandlerTest, TestObjectReleaseTracker, 
SegmentsInfoRequestHandlerTest, SpatialHeatmapFacetsTest, JvmMetricsTest, 
FileUtilsTest, TestSortableTextField, TestJettySolrRunner, 
ClassificationUpdateProcessorIntegrationTest, HdfsRecoveryZkTest, 
HttpSolrCallGetCoreTest, TestSimNodeAddedTrigger, TestQueryTypes, 
DistribDocExpirationUpdateProcessorTest, 
ChaosMonkeySafeLeaderWithPullReplicasTest, HdfsChaosMonkeySafeLeaderTest, 
ExternalFileFieldSortTest, ReplaceNodeNoTargetTest, SOLR749Test, 
ResponseLogComponentTest, IndexSizeTriggerMixedBoundsTest, TestSolr4Spatial, 
TestXIncludeConfig, TestFieldSortValues, TestJavabinTupleStreamParser, 
TestRestManager, SearchRateTriggerTest, SolrCLIZkUtilsTest, 
DocumentBuilderTest, HLLUtilTest, TestSolrConfigHandlerConcurrent, 
TestUninvertingReader, CloudMLTQParserTest, AsyncCallRequestStatusResponseTest, 
TestPKIAuthenticationPlugin, TestCSVLoader, ForceLeaderWithTlogReplicasTest, 
ExplicitHLLTest, TestBackupRepositoryFactory, TestZkChroot, 
TestDefaultStatsCache, ParsingFieldUpdateProcessorsTest, 
TestHighFrequencyDictionaryFactory, DistributedFacetPivotSmallTest, 
DistributedDebugComponentTest, TestLegacyTerms, DeleteShardTest, 
MetricUtilsTest, TestCustomDocTransformer, TestDistributedSearch, 
TestManagedStopFilterFactory, ZkShardTermsTest, TestSolrTestCaseJ4, 
TestLegacyBM25SimilarityFactory, LeaderVoteWaitTimeoutTest, SplitShardTest, 
LukeRequestHandlerTest, SSLMigrationTest, TestHttpShardHandlerFactory, 
ChangedSchemaMergeTest, TestFaceting, TestHdfsBackupRestoreCore, 
LeaderElectionContextKeyTest, SolrIndexSplitterTest, TestMultiWordSynonyms, 
CoreAdminCreateDiscoverTest, ResolveAnalyzerByNameTest, 
CreateCollectionCleanupTest, RequestLoggingTest, TestStressUserVersions, 
TestEmbeddedSolrServerAdminHandler, AuditLoggerPluginTest, 
PreAnalyzedFieldTest, BasicFunctionalityTest, UnloadDistributedZkTest, 
CurrencyRangeFacetCloudTest, TestNumericRangeQuery64, 
OpenExchangeRatesOrgProviderTest, TestFieldCacheSortRandom, 
TriggerCooldownIntegrationTest, TestReplicationHandlerDiskOverFlow, 
TestLegacyField, CustomCollectionTest, CoreSorterTest, 
DefaultValueUpdateProcessorTest, ScheduledTriggerIntegrationTest, 
TestMacroExpander, TestSimpleTextCodec, TestCryptoKeys, TestStreamBody, 
TestJsonFacetRefinement, TestCoreDiscovery, ZkControllerTest, 
TestDocBasedVersionConstraints, TestExportWriter, TestInitParams, 
TestSolrQueryParser, FacetPivotSmallTest, OverseerStatusTest, 
HttpPartitionOnCommitTest, TestSolrCLIRunExample, TestCloudNestedDocsSort, 
AtomicUpdatesTest, TestTestInjection, TestShortCircuitedRequests, 
TestCloudDeleteByQuery, TermsComponentTest, TestQuerySenderNoQuery, 
CdcrOpsAndBoundariesTest, TestReload, MoveReplicaHDFSFailoverTest, 
AutoAddReplicasPlanActionTest, ConcurrentCreateRoutedAliasTest, 
TestSuggestSpellingConverter, TestZkAclsWithHadoopAuth, TestDistributedMap, 
TestFieldResource, TestCursorMarkWithoutUniqueKey, TestCloudPivotFacet, 
TestPayloadCheckQParserPlugin, TestRawResponseWriter, SuggesterTSTTest, 
CursorPagingTest, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, 
SampleTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestDistributedMissingSort, 
TestGroupingSearch, TestHighlightDedupGrouping, TestJoin, TestRandomDVFaceting, 
TestRandomFaceting, TestSimpleTrackingShardHandler, TestSolrCoreProperties, 
PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, 
TestDeprecatedFilters, TestLuceneMatchVersion, 
TestReversedWildcardFilterFactory, TestLuceneIndexBackCompat, AddReplicaTest, 
AliasIntegrationTest, BasicDistributedZk2Test, BasicDistributedZkTest, 
CloudExitableDirectoryReaderTest, ClusterStateTest, ClusterStateUpdateTest, 
CollectionPropsTest, CollectionStateFormat2Test, CollectionsAPISolrJTest, 
DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, 
LeaderFailureAfterFreshStartTest, MetricsHistoryWithAuthIntegrationTest, 
MissingSegmentRecoveryTest, MoveReplicaTest, NodeMutatorTest, 
OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, 
PeerSyncReplicationTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, 
SystemCollectionCompatTest, TestCloudRecovery, TestConfigSetsAPIExclusivity, 
TestDistribDocBasedVersion, TestExactStatsCacheCloud, TestLRUStatsCacheCloud, 
TestLeaderElectionWithEmptyReplica, TestPullReplica, 
TestPullReplicaErrorHandling, TestRebalanceLeaders, 
TestSolrCloudWithDelegationTokens]
   [junit4] Completed [533/892 (1!)] on J1 in 6.02s, 9 tests, 1 failure <<< 
FAILURES!

[...truncated 46090 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25152/consoleText

[repro] Revision: e5df183a42967c0eb79b5c2c65cd3ab618318f23

[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line:  ant test  -Dtestcase=TestSolrCloudWithDelegationTokens 
-Dtests.method=testDelegationTokenRenew -Dtests.seed=E7DE86309870294B 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sq-XK 
-Dtests.timezone=Asia/Tokyo -Dtests.asserts=true -Dtests.file.encoding=UTF-8

[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       TestSolrCloudWithDelegationTokens
[repro] ant compile-test

[...truncated 3333 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestSolrCloudWithDelegationTokens" -Dtests.showOutput=onerror 
"-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" 
-Dtests.seed=E7DE86309870294B -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=sq-XK -Dtests.timezone=Asia/Tokyo -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[...truncated 115 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens
[repro] Exiting with code 0

[...truncated 43 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to