You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/12/13 16:58:52 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-14-ea+24) - Build
# 25152 - Unstable!
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...]